Apr 14 20:13:01 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 20:13:01 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 20:13:01 volumio-pi volumio[1251]: info: Received Get System Info Apr 14 20:13:01 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:13:01 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:13:01 volumio-pi volumio[1251]: info: Discovery: Getting this device information Apr 14 20:13:01 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:01 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:13:01 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 20:13:02 volumio-pi volumio[1251]: info: Received Get System Info Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:13:02 volumio-pi volumio[1251]: info: Discovery: Getting this device information Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:02 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:13:02 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:13:06 volumio-pi volumio[1251]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Apr 14 20:13:06 volumio-pi volumio[1251]: info: [squeezelite_mc] Proxy server stopped Apr 14 20:13:06 volumio-pi sudo[5166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Apr 14 20:13:06 volumio-pi sudo[5166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:13:06 volumio-pi systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Apr 14 20:13:08 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:13:08.682+02:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Apr 14 20:13:08 volumio-pi dbus-daemon[709]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.25" (uid=0 pid=2086 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=932 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 14 20:13:09 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 20:13:11 volumio-pi volumio[1251]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Apr 14 20:13:11 volumio-pi sudo[5169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Apr 14 20:13:11 volumio-pi sudo[5169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:13:11 volumio-pi systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 14 20:13:12 volumio-pi systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 14 20:13:12 volumio-pi systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 14 20:13:13 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:13:13.317+02:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Apr 14 20:13:16 volumio-pi volumio[1251]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Chiave API OpenWeatherMap non configurata. Aggiungila nelle impostazioni del servizio meteo. Apr 14 20:13:16 volumio-pi volumio[1251]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Impossibile recuperare il meteo: Chiave API OpenWeatherMap non configurata. Aggiungila nelle impostazioni del servizio meteo. Apr 14 20:13:16 volumio-pi volumio[1251]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:108:25) Apr 14 20:13:16 volumio-pi volumio[1251]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 14 20:13:16 volumio-pi volumio[1251]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Apr 14 20:13:17 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 20:13:17 volumio-pi volumio[1251]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 20:13:17 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 20:13:17 volumio-pi volumio[1251]: info: Received Get System Version Apr 14 20:13:17 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 20:13:17 volumio-pi volumio[1251]: info: Received Get System Info Apr 14 20:13:17 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:13:17 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:13:17 volumio-pi volumio[1251]: info: Discovery: Getting this device information Apr 14 20:13:17 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:13:17 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:13:32 volumio-pi kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Apr 14 20:13:34 volumio-pi bluealsa[1046]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_59_84_2B_88_A8_78, ...) Apr 14 20:13:40 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:13:40.444+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=1 index=0 tries=11 Apr 14 20:13:58 volumio-pi volumio[1251]: error: error Apr 14 20:13:58 volumio-pi volumio[1251]: error: error Apr 14 20:13:59 volumio-pi volumio[1251]: info: Clearing queue after UPNP request Apr 14 20:13:59 volumio-pi volumio[1251]: info: CoreStateMachine::ClearQueue Apr 14 20:13:59 volumio-pi volumio[1251]: info: CoreStateMachine::stop Apr 14 20:13:59 volumio-pi volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:13:59 volumio-pi volumio[1251]: info: CorePlayQueue::clearPlayQueue Apr 14 20:13:59 volumio-pi volumio[1251]: info: CorePlayQueue::saveQueue Apr 14 20:13:59 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:59 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:13:59 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:13:59.626+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 20:13:59 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:13:59.626+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" id= title= Apr 14 20:14:01 volumio-pi volumio[1251]: info: Starting UPNP Playback Apr 14 20:14:01 volumio-pi volumio[1251]: info: Preparing playback through UPNP Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:01 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 20:14:01 volumio-pi volumio[1251]: info: Apr 14 20:14:01 volumio-pi volumio[1251]: ---------------------------- MPD announces system playlist update Apr 14 20:14:01 volumio-pi volumio[1251]: info: Ignoring MPD Status Update Apr 14 20:14:01 volumio-pi volumio[1251]: info: ------------------------------ 1ms Apr 14 20:14:01 volumio-pi volumio[1251]: error: error Apr 14 20:14:01 volumio-pi volumio[1251]: error: error Apr 14 20:14:01 volumio-pi volumio[1251]: error: error Apr 14 20:14:01 volumio-pi volumio[1251]: error: error Apr 14 20:14:01 volumio-pi volumio[1251]: info: Apr 14 20:14:01 volumio-pi volumio[1251]: ---------------------------- MPD announces state update: player Apr 14 20:14:01 volumio-pi volumio[1251]: info: ControllerMpd::getState Apr 14 20:14:01 volumio-pi volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:14:01 volumio-pi volumio[1251]: info: Apr 14 20:14:01 volumio-pi volumio[1251]: ---------------------------- MPD announces state update: player Apr 14 20:14:01 volumio-pi volumio[1251]: info: ControllerMpd::getState Apr 14 20:14:01 volumio-pi volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:14:01 volumio-pi volumio[1251]: error: MPD returned error for command status: Failed to decode "http://127.0.0.1:57645/proxy/qobuz/677703B69593638D63A7E3CDB3DE312A.flac?proxy=false"; Failed to connect to 127.0.0.1 port 57645 after 0 ms: Couldn't connect to server: Couldn't connect to server Apr 14 20:14:01 volumio-pi volumio[1251]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 14 20:14:01 volumio-pi volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 14 20:14:01 volumio-pi volumio[1251]: info: sendMpdCommand status took 9 milliseconds Apr 14 20:14:01 volumio-pi volumio[1251]: verbose: ControllerMpd::parseState Apr 14 20:14:01 volumio-pi volumio[1251]: info: ControllerMpd::pushState Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreCommandRouter::servicePushState Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreStateMachine::pushState Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:01 volumio-pi volumio[1251]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 14 20:14:01 volumio-pi volumio[1251]: verbose: CURRENT POSITION 0 Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreStateMachine::syncState stateService stop Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreStateMachine::syncState currentStatus stop Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreStateMachine::pushState Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 volumio-pi volumio[1251]: info: No code Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreStateMachine::pushState Apr 14 20:14:01 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.005+02:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.005+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.007+02:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.007+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.007+02:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.007+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.011+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" id= title= Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.011+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" id= title= Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.011+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" id= title= Apr 14 20:14:02 volumio-pi volumio[1251]: info: ------------------------------ 55ms Apr 14 20:14:02 volumio-pi volumio[1251]: error: MPD returned error for command status: Failed to decode "http://127.0.0.1:57645/proxy/qobuz/677703B69593638D63A7E3CDB3DE312A.flac?proxy=false"; Failed to connect to 127.0.0.1 port 57645 after 0 ms: Couldn't connect to server: Couldn't connect to server Apr 14 20:14:02 volumio-pi volumio[1251]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 14 20:14:02 volumio-pi volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 14 20:14:02 volumio-pi volumio[1251]: info: sendMpdCommand status took 66 milliseconds Apr 14 20:14:02 volumio-pi volumio[1251]: info: sendMpdCommand clearerror took 59 milliseconds Apr 14 20:14:02 volumio-pi volumio[1251]: verbose: ControllerMpd::parseState Apr 14 20:14:02 volumio-pi volumio[1251]: info: ControllerMpd::pushState Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreCommandRouter::servicePushState Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreStateMachine::pushState Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:02 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:02 volumio-pi volumio[1251]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 14 20:14:02 volumio-pi volumio[1251]: verbose: CURRENT POSITION 0 Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreStateMachine::syncState stateService stop Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreStateMachine::syncState currentStatus stop Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreStateMachine::pushState Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:02 volumio-pi volumio[1251]: info: No code Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreStateMachine::pushState Apr 14 20:14:02 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.072+02:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.073+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.073+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" id= title= Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.074+02:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.074+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.075+02:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.075+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.075+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" id= title= Apr 14 20:14:02 volumio-pi volumio5-onboarding[2086]: time=2026-04-14T20:14:02.077+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.62:51722 @ 0x1926f90" id= title= Apr 14 20:14:02 volumio-pi volumio[1251]: info: ------------------------------ 124ms Apr 14 20:14:02 volumio-pi volumio[1251]: info: sendMpdCommand clearerror took 105 milliseconds Apr 14 20:14:03 volumio-pi volumio[1251]: info: Moving to next track after playback error Apr 14 20:14:03 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioPlay Apr 14 20:14:03 volumio-pi volumio[1251]: info: CoreStateMachine::play index 0 Apr 14 20:14:03 volumio-pi volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:14:03 volumio-pi volumio[1251]: info: CoreStateMachine::stop Apr 14 20:14:03 volumio-pi volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:14:03 volumio-pi volumio[1251]: info: CoreStateMachine::play index undefined Apr 14 20:14:03 volumio-pi volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 20:14:03 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:31 volumio-pi sudo[5350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 20:14:31 volumio-pi sudo[5350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:14:31 volumio-pi sudo[5351]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 20:14:31 volumio-pi sudo[5351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:14:31 volumio-pi sudo[5350]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:31 volumio-pi sudo[5351]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:31 volumio-pi volumio[1251]: verbose: New Socket.io Connection to 192.168.50.5 from 192.168.50.253 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 14 20:14:31 volumio-pi sudo[5355]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 20:14:31 volumio-pi sudo[5355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:14:31 volumio-pi sudo[5355]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:31 volumio-pi sudo[5357]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 20:14:31 volumio-pi sudo[5357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:14:31 volumio-pi sudo[5357]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:31 volumio-pi volumio[1251]: verbose: New Socket.io Connection to 192.168.50.5 from 192.168.50.253 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:31 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 20:14:31 volumio-pi volumio[1251]: info: Received Get System Info Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:14:31 volumio-pi volumio[1251]: info: Discovery: Getting this device information Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:31 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:31 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:31 volumio-pi volumio[1251]: info: Listing playlists Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 20:14:31 volumio-pi volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 20:14:32 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 20:14:32 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:32 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:33 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 20:14:33 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 20:14:33 volumio-pi volumio[1251]: info: Received Get System Info Apr 14 20:14:33 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:14:33 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:14:33 volumio-pi volumio[1251]: info: Discovery: Getting this device information Apr 14 20:14:33 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:33 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:33 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:14:34 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 20:14:34 volumio-pi volumio[1251]: info: Received Get System Info Apr 14 20:14:34 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:14:34 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:14:34 volumio-pi volumio[1251]: info: Discovery: Getting this device information Apr 14 20:14:34 volumio-pi volumio[1251]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:34 volumio-pi volumio[1251]: info: CorePlayQueue::getTrack 0 Apr 14 20:14:34 volumio-pi volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:14:36 volumio-pi systemd[1]: squeezelite.service: State 'stop-sigterm' timed out. Killing. Apr 14 20:14:36 volumio-pi systemd[1]: squeezelite.service: Killing process 1947 (squeezelite) with signal SIGKILL. Apr 14 20:14:36 volumio-pi systemd[1]: squeezelite.service: Killing process 1956 (squeezelite) with signal SIGKILL. Apr 14 20:14:36 volumio-pi systemd[1]: squeezelite.service: Main process exited, code=killed, status=9/KILL Apr 14 20:14:36 volumio-pi systemd[1]: squeezelite.service: Failed with result 'timeout'. Apr 14 20:14:36 volumio-pi systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Apr 14 20:14:36 volumio-pi systemd[1]: squeezelite.service: Consumed 21.618s CPU time. Apr 14 20:14:36 volumio-pi sudo[5169]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:36 volumio-pi sudo[5166]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:36 volumio-pi volumio[1251]: info: camilladsp respawn in 100 ms (attempt 1/10) Apr 14 20:14:36 volumio-pi kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Apr 14 20:14:36 volumio-pi kernel: xhci_hcd 0000:01:00.0: @000000040cd0ead0 00000000 00000000 0e000000 02028000 Apr 14 20:14:36 volumio-pi volumio[1251]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 14 20:14:36 volumio-pi volumio[1251]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Apr 14 20:14:36 volumio-pi sudo[5382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 14 20:14:36 volumio-pi sudo[5382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:14:36 volumio-pi sudo[5384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Apr 14 20:14:36 volumio-pi sudo[5384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:14:36 volumio-pi sudo[5382]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:36 volumio-pi sudo[5384]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:36 volumio-pi volumio[1251]: info: Disabling plugin squeezelite_mc Apr 14 20:14:36 volumio-pi volumio[1251]: info: Done. Apr 14 20:14:36 volumio-pi volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:14:36 volumio-pi volumio[1251]: TypeError: Cannot read properties of null (reading 'coreCommand') Apr 14 20:14:36 volumio-pi volumio[1251]: at SqueezeliteMCContext.toast (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:57:79) Apr 14 20:14:36 volumio-pi volumio[1251]: at /data/plugins/music_service/squeezelite_mc/dist/index.js:255:44 Apr 14 20:14:36 volumio-pi volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:14:37 volumio-pi sudo[5401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 20:13' Apr 14 20:14:37 volumio-pi sudo[5401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"