-- Logs begin at Mon 2026-05-25 08:01:58 CEST, end at Wed 2026-05-27 17:12:31 CEST. -- May 27 17:11:34 volumio---1 volumiologrotate[594]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 27 17:11:34 volumio---1 volumiologrotate[594]: ls: cannot access '-': No such file or directory May 27 17:11:34 volumio---1 volumiologrotate[594]: ls: cannot access '1': No such file or directory May 27 17:11:38 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 7 May 27 17:11:38 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:38 volumio---1 volumio[902]: info: Prefetching next song May 27 17:11:38 volumio---1 volumio[902]: info: DOING PREFETCH IN MPD May 27 17:11:38 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand add "NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac" May 27 17:11:38 volumio---1 volumio[902]: info: May 27 17:11:38 volumio---1 volumio[902]: ---------------------------- MPD announces system playlist update May 27 17:11:38 volumio---1 volumio[902]: info: Ignoring MPD Status Update May 27 17:11:38 volumio---1 volumio[902]: info: sendMpdCommand add "NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac" took 4 milliseconds May 27 17:11:38 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand consume 1 May 27 17:11:38 volumio---1 volumio[902]: info: May 27 17:11:38 volumio---1 volumio[902]: ---------------------------- MPD announces system playlist update May 27 17:11:38 volumio---1 volumio[902]: info: Ignoring MPD Status Update May 27 17:11:38 volumio---1 volumio[902]: info: May 27 17:11:38 volumio---1 volumio[902]: ---------------------------- MPD announces system playlist update May 27 17:11:38 volumio---1 volumio[902]: info: Ignoring MPD Status Update May 27 17:11:38 volumio---1 volumio[902]: info: ------------------------------ 6ms May 27 17:11:38 volumio---1 volumio[902]: info: sendMpdCommand consume 1 took 6 milliseconds May 27 17:11:38 volumio---1 volumio[902]: info: ------------------------------ 6ms May 27 17:11:38 volumio---1 volumio[902]: info: ------------------------------ 4ms May 27 17:11:42 volumio---1 volumio[902]: info: CoreStateMachine::startPlaybackTimer May 27 17:11:42 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: May 27 17:11:43 volumio---1 volumio[902]: ---------------------------- MPD announces system playlist update May 27 17:11:43 volumio---1 volumio[902]: info: Ignoring MPD Status Update May 27 17:11:43 volumio---1 volumio[902]: info: May 27 17:11:43 volumio---1 volumio[902]: ---------------------------- MPD announces state update: player May 27 17:11:43 volumio---1 volumio[902]: info: ControllerMpd::getState May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand status May 27 17:11:43 volumio---1 volumio[902]: info: May 27 17:11:43 volumio---1 volumio[902]: ---------------------------- MPD announces system playlist update May 27 17:11:43 volumio---1 volumio[902]: info: Ignoring MPD Status Update May 27 17:11:43 volumio---1 volumio[902]: info: May 27 17:11:43 volumio---1 volumio[902]: ---------------------------- MPD announces state update: player May 27 17:11:43 volumio---1 volumio[902]: info: ControllerMpd::getState May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand status May 27 17:11:43 volumio---1 volumio[902]: info: May 27 17:11:43 volumio---1 volumio[902]: ---------------------------- MPD announces system playlist update May 27 17:11:43 volumio---1 volumio[902]: info: Ignoring MPD Status Update May 27 17:11:43 volumio---1 volumio[902]: info: May 27 17:11:43 volumio---1 volumio[902]: ---------------------------- MPD announces state update: player May 27 17:11:43 volumio---1 volumio[902]: info: ControllerMpd::getState May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand status May 27 17:11:43 volumio---1 volumio[902]: info: ------------------------------ 14ms May 27 17:11:43 volumio---1 volumio[902]: info: sendMpdCommand status took 12 milliseconds May 27 17:11:43 volumio---1 volumio[902]: info: ------------------------------ 11ms May 27 17:11:43 volumio---1 volumio[902]: info: sendMpdCommand status took 9 milliseconds May 27 17:11:43 volumio---1 volumio[902]: info: ------------------------------ 9ms May 27 17:11:43 volumio---1 volumio[902]: info: sendMpdCommand status took 6 milliseconds May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::parseState May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::parseState May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::parseState May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 17:11:43 volumio---1 volumio[902]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 17:11:43 volumio---1 volumio[902]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 17:11:43 volumio---1 volumio[902]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::parseTrackInfo May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::parseTrackInfo May 27 17:11:43 volumio---1 volumio[902]: verbose: ControllerMpd::parseTrackInfo May 27 17:11:43 volumio---1 volumio[902]: info: ControllerMpd::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::servicePushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"C9. Variatio 24 A 1 Clav. Canone All’Ottava","artist":"Lang Lang","album":"Goldberg Variations - LP2","uri":"NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac","trackType":"flac"} May 27 17:11:43 volumio---1 volumio[902]: verbose: CURRENT POSITION 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::syncState stateService play May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::syncState currentStatus play May 27 17:11:43 volumio---1 volumio[902]: info: Received an update from plugin. extracting info from payload May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::volumioPushState May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::volumioPushState May 27 17:11:43 volumio---1 volumio[902]: info: ControllerMpd::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::servicePushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"C9. Variatio 24 A 1 Clav. Canone All’Ottava","artist":"Lang Lang","album":"Goldberg Variations - LP2","uri":"NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac","trackType":"flac"} May 27 17:11:43 volumio---1 volumio[902]: verbose: CURRENT POSITION 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::syncState stateService play May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::syncState currentStatus play May 27 17:11:43 volumio---1 volumio[902]: info: Received an update from plugin. extracting info from payload May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::volumioPushState May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::volumioPushState May 27 17:11:43 volumio---1 volumio[902]: info: ControllerMpd::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::servicePushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"C9. Variatio 24 A 1 Clav. Canone All’Ottava","artist":"Lang Lang","album":"Goldberg Variations - LP2","uri":"NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac","trackType":"flac"} May 27 17:11:43 volumio---1 volumio[902]: verbose: CURRENT POSITION 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::syncState stateService play May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::syncState currentStatus play May 27 17:11:43 volumio---1 volumio[902]: info: Received an update from plugin. extracting info from payload May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::volumioPushState May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::volumioPushState May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.189+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" state=STATUS_PLAYING positionMs=0 volume=66 May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.191+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" state=STATUS_PLAYING positionMs=0 volume=66 May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.191+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" id="music-library/NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac" title="C9. Variatio 24 A 1 Clav. Canone All’Ottava" May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.192+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" state=STATUS_PLAYING positionMs=0 volume=66 May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.192+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" id="music-library/NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac" title="C9. Variatio 24 A 1 Clav. Canone All’Ottava" May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.193+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" id="music-library/NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac" title="C9. Variatio 24 A 1 Clav. Canone All’Ottava" May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.194+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" state=STATUS_PLAYING positionMs=0 volume=66 May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.194+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" id="music-library/NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac" title="C9. Variatio 24 A 1 Clav. Canone All’Ottava" May 27 17:11:43 volumio---1 volumio[902]: info: ------------------------------ 90ms May 27 17:11:43 volumio---1 volumio[902]: info: ------------------------------ 87ms May 27 17:11:43 volumio---1 volumio[902]: info: ------------------------------ 84ms May 27 17:11:43 volumio---1 volumio[902]: info: CoreStateMachine::pushState May 27 17:11:43 volumio---1 volumio[902]: info: CorePlayQueue::getTrack 8 May 27 17:11:43 volumio---1 volumio[902]: info: CoreCommandRouter::volumioPushState May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.211+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" state=STATUS_PLAYING positionMs=0 volume=66 May 27 17:11:43 volumio---1 volumio5-onboarding[1211]: time=2026-05-27T17:11:43.213+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.110:43806,00:00:00:00:00:00%b6 @ 0x2bd0120" id="music-library/NAS/CD_Data_Base/CD_Data_Base/Lang_Lang_Goldberg_Variation_LP2/C9. Variatio 24 A 1 Clav. Canone All’Ottava.flac" title="C9. Variatio 24 A 1 Clav. Canone All’Ottava" May 27 17:12:30 volumio---1 volumio[902]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 17:12:30 volumio---1 volumio[902]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { May 27 17:12:30 volumio---1 volumio[902]: code: 'auth/network-request-failed', May 27 17:12:30 volumio---1 volumio[902]: a: null May 27 17:12:30 volumio---1 volumio[902]: } May 27 17:12:30 volumio---1 volumio[902]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 17:12:31 volumio---1 sudo[14335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-27 17:11 May 27 17:12:31 volumio---1 sudo[14335]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"