-- 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"