-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Fri 2025-05-23 23:50:32 CEST. -- May 23 23:49:22 volumio dbus-daemon[475]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) May 23 23:49:22 volumio volumio-time-update[470]: Failed to set time: Connection timed out May 23 23:49:22 volumio sudo[29068]: pam_unix(sudo:session): session closed for user root May 23 23:49:22 volumio volumio-time-update[470]: volumio-time-update-util: Error setting time. May 23 23:49:22 volumio volumio-time-update[470]: volumio-time-update-util: Retrying in 5 seconds... May 23 23:49:27 volumio volumio-time-update[470]: volumio-time-update-util: Fetching time from Volumio... May 23 23:49:27 volumio volumio-time-update[470]: volumio-time-update-util: Setting system time to: 2025-05-23 23:49:27 May 23 23:49:27 volumio sudo[29164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-23 23:49:27 May 23 23:49:27 volumio sudo[29164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:49:27 volumio dbus-daemon[475]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.809' (uid=0 pid=29165 comm="timedatectl set-time 2025-05-23 23:49:27 ") May 23 23:49:27 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device May 23 23:49:27 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. May 23 23:49:27 volumio systemd[1]: Failed to start Time & Date Service. May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPlay May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::play index undefined May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::startPlaybackTimer May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::clearAddPlayTracks NAS/hp-flac/U2 - Studio Discography 1980 - 2014/U2 - Under a Blood Red Sky (Live) 1983/08. '40' - U2.flac May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand stop May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand stop took 2 milliseconds May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clear May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:42 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand clear took 2 milliseconds May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand add "NAS/hp-flac/U2 - Studio Discography 1980 - 2014/U2 - Under a Blood Red Sky (Live) 1983/08. '40' - U2.flac" May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:42 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:42 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:42 volumio volumio[716]: error: updateQueue error: null May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:42 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 12ms May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand add "NAS/hp-flac/U2 - Studio Discography 1980 - 2014/U2 - Under a Blood Red Sky (Live) 1983/08. '40' - U2.flac" took 9 milliseconds May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 8ms May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 7ms May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand play May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:42 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:42 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 13ms May 23 23:49:42 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 9ms May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 7ms May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:42 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:42 volumio volumio[716]: info: May 23 23:49:42 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:42 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand status took 14 milliseconds May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand status took 13 milliseconds May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand status took 13 milliseconds May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand clearerror took 23 milliseconds May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand clearerror took 21 milliseconds May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand clearerror took 19 milliseconds May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand playlistinfo took 9 milliseconds May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand playlistinfo took 8 milliseconds May 23 23:49:42 volumio volumio[716]: info: sendMpdCommand playlistinfo took 7 milliseconds May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:42 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:42 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"'40'","artist":"U2","album":"Live / Under a Blood Red Sky","uri":"NAS/hp-flac/U2 - Studio Discography 1980 - 2014/U2 - Under a Blood Red Sky (Live) 1983/08. '40' - U2.flac","trackType":"flac"} May 23 23:49:42 volumio volumio[716]: verbose: CURRENT POSITION 2363 May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: No code May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"'40'","artist":"U2","album":"Live / Under a Blood Red Sky","uri":"NAS/hp-flac/U2 - Studio Discography 1980 - 2014/U2 - Under a Blood Red Sky (Live) 1983/08. '40' - U2.flac","trackType":"flac"} May 23 23:49:42 volumio volumio[716]: verbose: CURRENT POSITION 2363 May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: No code May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"'40'","artist":"U2","album":"Live / Under a Blood Red Sky","uri":"NAS/hp-flac/U2 - Studio Discography 1980 - 2014/U2 - Under a Blood Red Sky (Live) 1983/08. '40' - U2.flac","trackType":"flac"} May 23 23:49:42 volumio volumio[716]: verbose: CURRENT POSITION 2363 May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: No code May 23 23:49:42 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:42 volumio volumio[716]: info: CorePlayQueue::getTrack 2363 May 23 23:49:42 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 139ms May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 137ms May 23 23:49:42 volumio volumio[716]: info: ------------------------------ 136ms May 23 23:49:51 volumio volumio[716]: info: CoreCommandRouter::volumioGetQueue May 23 23:49:51 volumio volumio[716]: info: CoreStateMachine::getQueue May 23 23:49:51 volumio volumio[716]: info: CorePlayQueue::getQueue May 23 23:49:52 volumio volumio-time-update[470]: Failed to set time: Connection timed out May 23 23:49:52 volumio dbus-daemon[475]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) May 23 23:49:52 volumio sudo[29164]: pam_unix(sudo:session): session closed for user root May 23 23:49:52 volumio volumio-time-update[470]: volumio-time-update-util: Error setting time. May 23 23:49:52 volumio volumio-time-update[470]: volumio-time-update-util: Retrying in 5 seconds... May 23 23:49:54 volumio volumio[716]: info: CoreCommandRouter::volumioPlay May 23 23:49:54 volumio volumio[716]: info: CoreStateMachine::play index 5 May 23 23:49:54 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:49:54 volumio volumio[716]: info: CoreStateMachine::stop May 23 23:49:54 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:49:54 volumio volumio[716]: info: CoreStateMachine::play index undefined May 23 23:49:54 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:49:54 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:54 volumio volumio[716]: info: CoreStateMachine::startPlaybackTimer May 23 23:49:54 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::clearAddPlayTracks NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand stop May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand stop took 19 milliseconds May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clear May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:54 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand clear took 6 milliseconds May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand add "NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac" May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:54 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:54 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:54 volumio volumio[716]: error: updateQueue error: null May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:54 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:54 volumio volumio[716]: info: ------------------------------ 23ms May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand add "NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac" took 22 milliseconds May 23 23:49:54 volumio volumio[716]: info: ------------------------------ 18ms May 23 23:49:54 volumio volumio[716]: info: ------------------------------ 17ms May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand play May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:54 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:54 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:54 volumio volumio[716]: info: ------------------------------ 22ms May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand play took 20 milliseconds May 23 23:49:54 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:54 volumio volumio[716]: info: ------------------------------ 19ms May 23 23:49:54 volumio volumio[716]: info: ------------------------------ 17ms May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:54 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:54 volumio volumio[716]: info: May 23 23:49:54 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:54 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand status took 18 milliseconds May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand status took 17 milliseconds May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand status took 16 milliseconds May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand clearerror took 43 milliseconds May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand clearerror took 41 milliseconds May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand clearerror took 40 milliseconds May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand playlistinfo took 34 milliseconds May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand playlistinfo took 34 milliseconds May 23 23:49:54 volumio volumio[716]: info: sendMpdCommand playlistinfo took 33 milliseconds May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:54 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:55 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:55 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Water Under the Bridge","artist":"Adele","album":"25","uri":"NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac","trackType":"flac"} May 23 23:49:55 volumio volumio[716]: verbose: CURRENT POSITION 5 May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: No code May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Water Under the Bridge","artist":"Adele","album":"25","uri":"NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac","trackType":"flac"} May 23 23:49:55 volumio volumio[716]: verbose: CURRENT POSITION 5 May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: No code May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Water Under the Bridge","artist":"Adele","album":"25","uri":"NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac","trackType":"flac"} May 23 23:49:55 volumio volumio[716]: verbose: CURRENT POSITION 5 May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: No code May 23 23:49:55 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:55 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:55 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:55 volumio volumio[716]: info: ------------------------------ 158ms May 23 23:49:55 volumio volumio[716]: info: ------------------------------ 156ms May 23 23:49:55 volumio volumio[716]: info: ------------------------------ 155ms May 23 23:49:56 volumio volumio[716]: info: CoreCommandRouter::volumioPlay May 23 23:49:56 volumio volumio[716]: info: CoreStateMachine::play index 5 May 23 23:49:56 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:49:56 volumio volumio[716]: info: CoreStateMachine::stop May 23 23:49:56 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:49:56 volumio volumio[716]: info: CoreStateMachine::play index undefined May 23 23:49:56 volumio volumio[716]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 23:49:56 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:56 volumio volumio[716]: info: CoreStateMachine::startPlaybackTimer May 23 23:49:56 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::clearAddPlayTracks NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand stop May 23 23:49:56 volumio volumio[716]: info: sendMpdCommand stop took 15 milliseconds May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clear May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:56 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:56 volumio volumio[716]: info: sendMpdCommand clear took 20 milliseconds May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand add "NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac" May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:56 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:56 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:56 volumio volumio[716]: error: updateQueue error: null May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:56 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:56 volumio volumio[716]: info: ------------------------------ 50ms May 23 23:49:56 volumio volumio[716]: info: sendMpdCommand add "NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac" took 48 milliseconds May 23 23:49:56 volumio volumio[716]: info: ------------------------------ 44ms May 23 23:49:56 volumio volumio[716]: info: ------------------------------ 43ms May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand play May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:56 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:56 volumio volumio[716]: info: ------------------------------ 20ms May 23 23:49:56 volumio volumio[716]: info: ------------------------------ 15ms May 23 23:49:56 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces system playlist update May 23 23:49:56 volumio volumio[716]: info: Ignoring MPD Status Update May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 23:49:56 volumio volumio[716]: info: sendMpdCommand status took 29 milliseconds May 23 23:49:56 volumio volumio[716]: info: ------------------------------ 28ms May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:56 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:56 volumio volumio[716]: info: May 23 23:49:56 volumio volumio[716]: ---------------------------- MPD announces state update: player May 23 23:49:56 volumio volumio[716]: info: ControllerMpd::getState May 23 23:49:56 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand status May 23 23:49:57 volumio volumio[716]: info: sendMpdCommand clearerror took 39 milliseconds May 23 23:49:57 volumio volumio[716]: info: sendMpdCommand playlistinfo took 37 milliseconds May 23 23:49:57 volumio volumio[716]: info: sendMpdCommand status took 35 milliseconds May 23 23:49:57 volumio volumio[716]: info: sendMpdCommand status took 33 milliseconds May 23 23:49:57 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:57 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:57 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:57 volumio volumio[716]: verbose: ControllerMpd::parseState May 23 23:49:57 volumio volumio[716]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 23:49:57 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Water Under the Bridge","artist":"Adele","album":"25","uri":"NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac","trackType":"flac"} May 23 23:49:57 volumio volumio[716]: verbose: CURRENT POSITION 5 May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: No code May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: ------------------------------ 105ms May 23 23:49:57 volumio volumio[716]: info: sendMpdCommand playlistinfo took 62 milliseconds May 23 23:49:57 volumio volumio[716]: info: sendMpdCommand playlistinfo took 62 milliseconds May 23 23:49:57 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:57 volumio volumio[716]: verbose: ControllerMpd::parseTrackInfo May 23 23:49:57 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Water Under the Bridge","artist":"Adele","album":"25","uri":"NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac","trackType":"flac"} May 23 23:49:57 volumio volumio[716]: verbose: CURRENT POSITION 5 May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: No code May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: ControllerMpd::pushState May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::servicePushState May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Water Under the Bridge","artist":"Adele","album":"25","uri":"NAS/hp-flac/Adele - 25/CD/06 Water Under the Bridge.flac","trackType":"flac"} May 23 23:49:57 volumio volumio[716]: verbose: CURRENT POSITION 5 May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::syncState stateService stop May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::syncState currentStatus stop May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: No code May 23 23:49:57 volumio volumio[716]: info: CoreStateMachine::pushState May 23 23:49:57 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:49:57 volumio volumio[716]: info: CoreCommandRouter::volumioPushState May 23 23:49:57 volumio volumio[716]: info: ------------------------------ 152ms May 23 23:49:57 volumio volumio[716]: info: ------------------------------ 152ms May 23 23:49:57 volumio volumio-time-update[470]: volumio-time-update-util: Fetching time from Volumio... May 23 23:49:57 volumio volumio-time-update[470]: volumio-time-update-util: Setting system time to: 2025-05-23 23:49:57 May 23 23:49:57 volumio sudo[29218]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-23 23:49:57 May 23 23:49:57 volumio sudo[29218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:49:57 volumio dbus-daemon[475]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.810' (uid=0 pid=29219 comm="timedatectl set-time 2025-05-23 23:49:57 ") May 23 23:49:57 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device May 23 23:49:57 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. May 23 23:49:57 volumio systemd[1]: Failed to start Time & Date Service. May 23 23:50:06 volumio volumio[716]: info: CoreCommandRouter::volumioGetState May 23 23:50:06 volumio volumio[716]: info: CorePlayQueue::getTrack 5 May 23 23:50:22 volumio dbus-daemon[475]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) May 23 23:50:22 volumio volumio-time-update[470]: Failed to set time: Connection timed out May 23 23:50:22 volumio sudo[29218]: pam_unix(sudo:session): session closed for user root May 23 23:50:22 volumio volumio-time-update[470]: volumio-time-update-util: Error setting time. May 23 23:50:22 volumio volumio-time-update[470]: volumio-time-update-util: Retrying in 5 seconds... May 23 23:50:27 volumio volumio-time-update[470]: volumio-time-update-util: Fetching time from Volumio... May 23 23:50:28 volumio volumio-time-update[470]: volumio-time-update-util: Setting system time to: 2025-05-23 23:50:28 May 23 23:50:28 volumio sudo[29314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-23 23:50:28 May 23 23:50:28 volumio sudo[29314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 23:50:28 volumio dbus-daemon[475]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.811' (uid=0 pid=29315 comm="timedatectl set-time 2025-05-23 23:50:28 ") May 23 23:50:28 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device May 23 23:50:28 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. May 23 23:50:28 volumio systemd[1]: Failed to start Time & Date Service. May 23 23:50:31 volumio volumio[716]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 23:50:31 volumio volumio[716]: [Error: ENOSPC: no space left on device, write] { May 23 23:50:31 volumio volumio[716]: errno: -28, May 23 23:50:31 volumio volumio[716]: code: 'ENOSPC', May 23 23:50:31 volumio volumio[716]: syscall: 'write' May 23 23:50:31 volumio volumio[716]: } May 23 23:50:31 volumio volumio[716]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 23:50:32 volumio sudo[29325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 23:49 May 23 23:50:32 volumio sudo[29325]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"