-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-10-05 20:16:58 CEST. -- Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=trace msg="emitting websocket event: not_playing" Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:70aIu79gwrnRmAzQa6FPiV","play_origin":"playlist"}} Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: error: Failed to decode event: not_playing Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=trace msg="emitting websocket event: will_play" Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6nleD42Dimnc5fHnpA2T2R","play_origin":"playlist"}} Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=info msg="loaded track \"You Were Mine\" (paused: false, position: 0ms, duration: 223577ms, prefetched: true)" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=trace msg="scheduling prefetch in 193s" Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=trace msg="emitting websocket event: metadata" Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6nleD42Dimnc5fHnpA2T2R","name":"You Were Mine","artist_names":["Tami Neilson"],"album_name":"CHICKABOOM!","album_cover_url":"https://i.scdn.co/image/ab67616d00001e023d820d81abb537347672b97a","position":0,"duration":223577,"release_date":"year:2020 month:2 day:14","track_number":5,"disc_number":1}} Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 20:15:22 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:22+02:00" level=trace msg="emitting websocket event: playing" Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6nleD42Dimnc5fHnpA2T2R","play_origin":"playlist"}} Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: PUSH STATE SPOTIFY Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: {"status":"play","service":"spop","title":"You Were Mine","artist":"Tami Neilson","album":"CHICKABOOM!","albumart":"https://i.scdn.co/image/ab67616d00001e023d820d81abb537347672b97a","uri":"spotify:track:6nleD42Dimnc5fHnpA2T2R","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::servicePushState Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: info: CoreStateMachine::pushState Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioPushState Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: PUSH STATE SPOTIFY Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: SPOTIFY: {"status":"play","service":"spop","title":"You Were Mine","artist":"Tami Neilson","album":"CHICKABOOM!","albumart":"https://i.scdn.co/image/ab67616d00001e023d820d81abb537347672b97a","uri":"spotify:track:6nleD42Dimnc5fHnpA2T2R","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::servicePushState Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: info: CoreStateMachine::pushState Oct 05 20:15:22 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioPushState Oct 05 20:15:23 philips-proto-dac-1387 volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 05 20:15:35 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:35+02:00" level=debug msg="fetched chunk 4/16, size: 524288" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:15:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:15:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:15:36 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:15:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:15:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:15:36 philips-proto-dac-1387 volumio[836]: verbose: New Socket.io Connection to 192.168.2.11 from 192.168.2.10 UA: Mozilla/5.0 (Linux; Android 14; Pixel 7 Pro Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.71 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 05 20:15:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: Listing playlists Oct 05 20:15:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 20:15:41 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:15:41 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 20:15:48 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:15:48+02:00" level=debug msg="fetched chunk 5/16, size: 524288" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:15:52 philips-proto-dac-1387 volumio[836]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/audiophonicsonoff/volumio/buster/armhf Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/audiophonicsonoff/volumio/buster/armhf Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: Folder /tmp/plugins removed Oct 05 20:15:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access '/var/log/samba/log.wb-PHILIPS': No such file or directory Oct 05 20:15:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access 'PROTO': No such file or directory Oct 05 20:15:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access 'DAC': No such file or directory Oct 05 20:15:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access '1387': No such file or directory Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: Check plugin dependencies Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: Checking if plugin already exists Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: Rename folder Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: Folder /tmp/downloaded_plugin.zip removed Oct 05 20:15:54 philips-proto-dac-1387 volumio[836]: info: Move to category Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Checking if install.sh is present Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Executing install.sh Oct 05 20:15:55 philips-proto-dac-1387 sudo[4450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/audiophonicsonoff/install.sh Oct 05 20:15:55 philips-proto-dac-1387 sudo[4450]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Installing Audiophonics on/off and its dependencies... Oct 05 20:15:55 philips-proto-dac-1387 sudo[4450]: pam_unix(sudo:session): session closed for user root Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Install script completed Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Adding reference to registry Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Done installing plugin. Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: No packages needed, only node_modules are required Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: Plugin install end detected on script Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Folder /tmp/plugins removed Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Folder /tmp/downloaded_plugin.zip removed Oct 05 20:15:55 philips-proto-dac-1387 volumio[836]: info: Folder /data/temp removed Oct 05 20:15:57 philips-proto-dac-1387 volumio[836]: info: Enabling plugin audiophonicsonoff Oct 05 20:15:57 philips-proto-dac-1387 volumio[836]: info: Loading plugin "audiophonicsonoff"... Oct 05 20:15:57 philips-proto-dac-1387 volumio[836]: info: Audiophonics on/off initiated Oct 05 20:15:57 philips-proto-dac-1387 volumio[836]: info: PLUGIN START: audiophonicsonoff Oct 05 20:15:57 philips-proto-dac-1387 volumio[836]: info: Configuring GPIO pins Oct 05 20:15:57 philips-proto-dac-1387 volumio[836]: info: Error: Error: EINVAL: invalid argument, write Oct 05 20:15:57 philips-proto-dac-1387 kernel: export_store: invalid GPIO 4 Oct 05 20:15:58 philips-proto-dac-1387 volumio[836]: info: Enabling plugin audiophonicsonoff Oct 05 20:15:58 philips-proto-dac-1387 volumio[836]: info: Loading plugin "audiophonicsonoff"... Oct 05 20:15:58 philips-proto-dac-1387 volumio[836]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 05 20:15:58 philips-proto-dac-1387 volumio[836]: info: Audiophonics on/off initiated Oct 05 20:15:58 philips-proto-dac-1387 volumio[836]: info: PLUGIN START: audiophonicsonoff Oct 05 20:15:58 philips-proto-dac-1387 volumio[836]: info: Configuring GPIO pins Oct 05 20:15:58 philips-proto-dac-1387 volumio[836]: info: Error: Error: EINVAL: invalid argument, write Oct 05 20:15:58 philips-proto-dac-1387 kernel: export_store: invalid GPIO 4 Oct 05 20:16:00 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:16:00+02:00" level=debug msg="fetched chunk 6/16, size: 524288" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:16:01 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 20:16:13 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:16:13+02:00" level=debug msg="fetched chunk 7/16, size: 524288" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: verbose: New Socket.io Connection to 192.168.2.11:3000 from 192.168.2.10 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 05 20:16:16 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 05 20:16:18 philips-proto-dac-1387 sudo[4457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 20:16:18 philips-proto-dac-1387 sudo[4457]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:18 philips-proto-dac-1387 sudo[4457]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:18 philips-proto-dac-1387 sudo[4459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 20:16:18 philips-proto-dac-1387 sudo[4459]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:18 philips-proto-dac-1387 sudo[4459]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:18 philips-proto-dac-1387 volumio[836]: verbose: New Socket.io Connection to 192.168.2.11 from 192.168.2.10 UA: Mozilla/5.0 (Linux; Android 14; Pixel 7 Pro Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.71 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 05 20:16:19 philips-proto-dac-1387 sudo[4463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 20:16:19 philips-proto-dac-1387 sudo[4463]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:19 philips-proto-dac-1387 sudo[4463]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:19 philips-proto-dac-1387 sudo[4466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 20:16:19 philips-proto-dac-1387 sudo[4466]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:19 philips-proto-dac-1387 sudo[4466]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: verbose: New Socket.io Connection to 192.168.2.11 from 192.168.2.10 UA: Mozilla/5.0 (Linux; Android 14; Pixel 7 Pro Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.71 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: Listing playlists Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 20:16:19 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 20:16:20 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:21 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:22 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:16:22 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 20:16:26 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:16:26+02:00" level=debug msg="fetched chunk 8/16, size: 524288" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:16:27 philips-proto-dac-1387 volumio[836]: info: Enabling plugin audiophonicsonoff Oct 05 20:16:27 philips-proto-dac-1387 volumio[836]: info: Loading plugin "audiophonicsonoff"... Oct 05 20:16:27 philips-proto-dac-1387 volumio[836]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 05 20:16:27 philips-proto-dac-1387 volumio[836]: info: Audiophonics on/off initiated Oct 05 20:16:27 philips-proto-dac-1387 volumio[836]: info: PLUGIN START: audiophonicsonoff Oct 05 20:16:27 philips-proto-dac-1387 volumio[836]: info: Configuring GPIO pins Oct 05 20:16:27 philips-proto-dac-1387 volumio[836]: info: Error: Error: EINVAL: invalid argument, write Oct 05 20:16:27 philips-proto-dac-1387 kernel: export_store: invalid GPIO 4 Oct 05 20:16:28 philips-proto-dac-1387 volumio[836]: info: Enabling plugin audiophonicsonoff Oct 05 20:16:28 philips-proto-dac-1387 volumio[836]: info: Loading plugin "audiophonicsonoff"... Oct 05 20:16:28 philips-proto-dac-1387 volumio[836]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 05 20:16:28 philips-proto-dac-1387 volumio[836]: info: Audiophonics on/off initiated Oct 05 20:16:28 philips-proto-dac-1387 volumio[836]: info: PLUGIN START: audiophonicsonoff Oct 05 20:16:28 philips-proto-dac-1387 volumio[836]: info: Configuring GPIO pins Oct 05 20:16:28 philips-proto-dac-1387 volumio[836]: info: Error: Error: EINVAL: invalid argument, write Oct 05 20:16:28 philips-proto-dac-1387 kernel: export_store: invalid GPIO 4 Oct 05 20:16:29 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 05 20:16:30 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 20:16:30 philips-proto-dac-1387 volumio[836]: info: Loaded the previous config. Oct 05 20:16:30 philips-proto-dac-1387 volumio[836]: info: ## populating UI... Oct 05 20:16:30 philips-proto-dac-1387 volumio[836]: info: 1/1 settings loaded Oct 05 20:16:30 philips-proto-dac-1387 volumio[836]: info: Populated config screen. Oct 05 20:16:33 philips-proto-dac-1387 sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 20:16:33 philips-proto-dac-1387 sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:33 philips-proto-dac-1387 sudo[4470]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:33 philips-proto-dac-1387 sudo[4473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 20:16:33 philips-proto-dac-1387 sudo[4473]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:33 philips-proto-dac-1387 sudo[4473]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:33 philips-proto-dac-1387 volumio[836]: verbose: New Socket.io Connection to 192.168.2.11 from 192.168.2.10 UA: Mozilla/5.0 (Linux; Android 14; Pixel 7 Pro Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.71 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 05 20:16:34 philips-proto-dac-1387 sudo[4476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 20:16:34 philips-proto-dac-1387 sudo[4476]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:34 philips-proto-dac-1387 sudo[4476]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:34 philips-proto-dac-1387 sudo[4479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 20:16:34 philips-proto-dac-1387 sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:34 philips-proto-dac-1387 sudo[4479]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: verbose: New Socket.io Connection to 192.168.2.11 from 192.168.2.10 UA: Mozilla/5.0 (Linux; Android 14; Pixel 7 Pro Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.71 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: Listing playlists Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 20:16:34 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 20:16:35 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:36 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 20:16:37 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: Received Get System Version Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:38 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:39 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:16:39+02:00" level=debug msg="fetched chunk 9/16, size: 524288" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:16:40 philips-proto-dac-1387 volumio[836]: info: Enabling plugin audiophonicsonoff Oct 05 20:16:40 philips-proto-dac-1387 volumio[836]: info: Loading plugin "audiophonicsonoff"... Oct 05 20:16:40 philips-proto-dac-1387 volumio[836]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 05 20:16:40 philips-proto-dac-1387 volumio[836]: info: Audiophonics on/off initiated Oct 05 20:16:40 philips-proto-dac-1387 volumio[836]: info: PLUGIN START: audiophonicsonoff Oct 05 20:16:40 philips-proto-dac-1387 volumio[836]: info: Configuring GPIO pins Oct 05 20:16:40 philips-proto-dac-1387 volumio[836]: info: Error: Error: EINVAL: invalid argument, write Oct 05 20:16:40 philips-proto-dac-1387 kernel: export_store: invalid GPIO 4 Oct 05 20:16:41 philips-proto-dac-1387 volumio[836]: info: Enabling plugin audiophonicsonoff Oct 05 20:16:41 philips-proto-dac-1387 volumio[836]: info: Loading plugin "audiophonicsonoff"... Oct 05 20:16:41 philips-proto-dac-1387 volumio[836]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 05 20:16:41 philips-proto-dac-1387 volumio[836]: info: Audiophonics on/off initiated Oct 05 20:16:41 philips-proto-dac-1387 volumio[836]: info: PLUGIN START: audiophonicsonoff Oct 05 20:16:41 philips-proto-dac-1387 volumio[836]: info: Configuring GPIO pins Oct 05 20:16:41 philips-proto-dac-1387 volumio[836]: info: Error: Error: EINVAL: invalid argument, write Oct 05 20:16:41 philips-proto-dac-1387 kernel: export_store: invalid GPIO 4 Oct 05 20:16:44 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 05 20:16:46 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:46 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:46 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:46 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:46 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:50 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 20:16:52 philips-proto-dac-1387 go-librespot[1158]: time="2024-10-05T20:16:52+02:00" level=debug msg="fetched chunk 10/16, size: 524288" uri="spotify:track:6nleD42Dimnc5fHnpA2T2R" Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: Received Get System Version Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: Received Get System Info Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: Discovery: Getting this device information Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::volumioGetState Oct 05 20:16:52 philips-proto-dac-1387 volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 20:16:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access '/var/log/samba/log.wb-PHILIPS': No such file or directory Oct 05 20:16:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access 'PROTO': No such file or directory Oct 05 20:16:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access 'DAC': No such file or directory Oct 05 20:16:54 philips-proto-dac-1387 volumiologrotate[535]: ls: cannot access '1387': No such file or directory Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: info: PLUGIN onReboot : networkfs Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: info: PLUGIN onReboot : touch_display Oct 05 20:16:57 philips-proto-dac-1387 sudo[4533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 05 20:16:57 philips-proto-dac-1387 sudo[4533]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: info: PLUGIN onReboot : audiophonicsonoff Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 20:16:57 philips-proto-dac-1387 sudo[4533]: pam_unix(sudo:session): session closed for user root Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: TypeError: Cannot read property 'writeSync' of undefined Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at Socket.emit (events.js:315:20) Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 05 20:16:57 philips-proto-dac-1387 volumio[836]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 20:16:57 philips-proto-dac-1387 sudo[4535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Oct 05 20:16:57 philips-proto-dac-1387 sudo[4535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 20:16:57 philips-proto-dac-1387 systemd[1]: Stopping Volumio Kiosk... Oct 05 20:16:57 philips-proto-dac-1387 startx[1157]: xinit: connection to X server lost Oct 05 20:16:57 philips-proto-dac-1387 startx[1157]: [1B blob data] Oct 05 20:16:58 philips-proto-dac-1387 startx[1157]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Oct 05 20:16:58 philips-proto-dac-1387 sudo[4552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 20:15 Oct 05 20:16:58 philips-proto-dac-1387 sudo[4552]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="35b821ff7c588ea2ff28d7b2bc9bb02e49e3ab6c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8ef80245b1f7b577b29fe08b77efdc88d53369e5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 18 Sep 2024 02:17:42 PM CEST" VOLUMIO_VERSION="3.758" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cfa71b4386e95e4d11d00ae35602129a"