-- Logs begin at Mon 2026-05-25 23:48:30 UTC, end at Tue 2026-05-26 00:15:59 UTC. -- May 26 00:14:00 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:04 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:08 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:12 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:15 volumio-main kernel: hwmon hwmon1: Undervoltage detected! May 26 00:14:16 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:20 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 26 00:14:20 volumio-main sudo[10223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/USB May 26 00:14:20 volumio-main sudo[10223]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:14:20 volumio-main systemd[1]: mnt-NAS-USB.mount: Succeeded. May 26 00:14:20 volumio-main sudo[10223]: pam_unix(sudo:session): session closed for user root May 26 00:14:20 volumio-main volumio[973]: info: Share USB successfully unmounted May 26 00:14:20 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:21 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 9 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 6 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 4 milliseconds May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: [1779754463267] Database update started May 26 00:14:23 volumio-main volumio[973]: info: MPD Database updated - AlbumList cache refreshed May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: database May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: MPD Database updated - AlbumList cache refreshed May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: database May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 83 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 53 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 35 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 18 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 13 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 13 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 9 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 8 milliseconds May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.345Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 254ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 253ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 251ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 251ms May 26 00:14:23 volumio-main volumio[973]: info: MPD Database updated - AlbumList cache refreshed May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: database May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: MPD Database updated - AlbumList cache refreshed May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: database May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: MPD Database updated - AlbumList cache refreshed May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: database May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: May 26 00:14:23 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 107 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 101 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 102 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 76 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 56 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 56 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 26 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 27 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 27 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 9 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 9 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 8 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 9 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 8 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand status took 8 milliseconds May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.581Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.582Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.582Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.584Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.586Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.587Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 325ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 322ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 323ms May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 188 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 188 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 149 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 149 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 113 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 113 milliseconds May 26 00:14:23 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 75 milliseconds May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:23 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:23 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio[973]: info: No code May 26 00:14:23 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:23 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.707Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.708Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.709Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.710Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.710Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:23.711Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 451ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 370ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 371ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 354ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 355ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 355ms May 26 00:14:23 volumio-main volumio[973]: info: ------------------------------ 356ms May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:23 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:24 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:28 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:29 volumio-main kernel: hwmon hwmon1: Voltage normalised May 26 00:14:32 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:36 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:40 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand update May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand update took 2 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: May 26 00:14:43 volumio-main volumio[973]: ---------------------------- MPD announces state update: update May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 6 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 6 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 6 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 5 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 4 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 4 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 4 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 3 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 3 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 3 milliseconds May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatetrue May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 202 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand status took 201 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 156 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 138 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 120 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 102 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 84 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 66 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 48 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 27 milliseconds May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 10 milliseconds May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: info: Command Router : Notfying DB Updatefalse May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::Close All Modals sent May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.393Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.394Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.395Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.396Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.397Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.400Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.400Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.401Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.402Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 282ms May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 281ms May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 281ms May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 280ms May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.403Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 280ms May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 280ms May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 279ms May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 138 milliseconds May 26 00:14:43 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:14:43 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: CorePlayQueue::getTrack 7 May 26 00:14:43 volumio-main volumio[973]: 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":"514.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/514.m4a","trackType":"m4a"} May 26 00:14:43 volumio-main volumio[973]: verbose: CURRENT POSITION 7 May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio[973]: info: No code May 26 00:14:43 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:14:43 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.496Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:14:43.497Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:14:43 volumio-main volumio[973]: info: ------------------------------ 373ms May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:43 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:14:44 volumio-main kernel: hwmon hwmon1: Undervoltage detected! May 26 00:14:44 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:48 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:52 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 00:14:54 volumio-main volumio[973]: info: CoreCommandRouter::volumioGetState May 26 00:14:56 volumio-main kernel: hwmon hwmon1: Voltage normalised May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPlay May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::play index 3 May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::play index undefined May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:00 volumio-main volumio[973]: info: CorePlayQueue::getTrack 3 May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::startPlaybackTimer May 26 00:15:00 volumio-main volumio[973]: info: CorePlayQueue::getTrack 3 May 26 00:15:00 volumio-main volumio[973]: info: [1779754500634] ControllerUPNPBrowser::clearAddPlayTrack May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand stop May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand stop took 1 milliseconds May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clear May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand clear took 0 milliseconds May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.188:8200/MediaItems/512.m4a" May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: error: Upnp client error: Error: This socket has been ended by the other party May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.188:8200/MediaItems/512.m4a" May 26 00:15:00 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:00 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:00 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:00 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:00 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 34ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 33ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 32ms May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand add "http://192.168.1.188:8200/MediaItems/512.m4a" took 2 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand play May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:00 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 3ms May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand play took 2 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 2ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 1ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 1ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 2ms May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:00 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/512.m4a; got HTTP status 403 May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand status took 3 milliseconds May 26 00:15:00 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/512.m4a; got HTTP status 403 May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand status took 4 milliseconds May 26 00:15:00 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/512.m4a; got HTTP status 403 May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand status took 3 milliseconds May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:00 volumio-main volumio[973]: info: May 26 00:15:00 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:00 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/512.m4a; got HTTP status 403 May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand clearerror took 5 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand clearerror took 5 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 4 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand status took 2 milliseconds May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: CorePlayQueue::getTrack 3 May 26 00:15:00 volumio-main volumio[973]: 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":"512.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/512.m4a","trackType":"m4a"} May 26 00:15:00 volumio-main volumio[973]: verbose: CURRENT POSITION 3 May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: No code May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: CorePlayQueue::getTrack 3 May 26 00:15:00 volumio-main volumio[973]: 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":"512.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/512.m4a","trackType":"m4a"} May 26 00:15:00 volumio-main volumio[973]: verbose: CURRENT POSITION 3 May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: No code May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: CorePlayQueue::getTrack 3 May 26 00:15:00 volumio-main volumio[973]: 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":"512.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/512.m4a","trackType":"m4a"} May 26 00:15:00 volumio-main volumio[973]: verbose: CURRENT POSITION 3 May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: No code May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:00.717Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:00 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:00.718Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 29ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 29ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 28ms May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand clearerror took 33 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 32 milliseconds May 26 00:15:00 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 31 milliseconds May 26 00:15:00 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: CorePlayQueue::getTrack 3 May 26 00:15:00 volumio-main volumio[973]: 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":"512.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/512.m4a","trackType":"m4a"} May 26 00:15:00 volumio-main volumio[973]: verbose: CURRENT POSITION 3 May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: No code May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: CorePlayQueue::getTrack 3 May 26 00:15:00 volumio-main volumio[973]: 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":"512.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/512.m4a","trackType":"m4a"} May 26 00:15:00 volumio-main volumio[973]: verbose: CURRENT POSITION 3 May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio[973]: info: No code May 26 00:15:00 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:00 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:00 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:00.741Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:00 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:00.741Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:00 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:00.741Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:00 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:00.742Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 51ms May 26 00:15:00 volumio-main volumio[973]: info: ------------------------------ 46ms May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:00 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:01 volumio-main kernel: hwmon hwmon1: Undervoltage detected! May 26 00:15:06 volumio-main volumio[973]: info: CoreCommandRouter::volumioGetState May 26 00:15:11 volumio-main kernel: hwmon hwmon1: Voltage normalised May 26 00:15:14 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 00:15:14 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:15 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 00:15:15 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:15 volumio-main kernel: hwmon hwmon1: Undervoltage detected! May 26 00:15:16 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 00:15:16 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:20 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 00:15:20 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:22 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 00:15:22 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$0 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$1 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$2 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$3 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$4 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$5 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$6 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$7 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$8 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$9 May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$A May 26 00:15:22 volumio-main volumio[973]: info: Preloading song: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$B May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$0 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$1 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: No valid Plugin REST Endpoint May 26 00:15:23 volumio-main volumio[973]: info: No valid Plugin REST Endpoint May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$2 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$3 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$4 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$5 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$6 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$7 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$8 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$9 in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$A in service upnp_browser May 26 00:15:23 volumio-main volumio[973]: info: Exploding uri upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$B in service upnp_browser May 26 00:15:26 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::ClearQueue May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::clearPlayQueue May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::saveQueue May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushQueue May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::addQueueItems May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::addQueueItems May 26 00:15:26 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$0 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$0 May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushQueue May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::saveQueue May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::updateTrackBlock May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrackBlock May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPlay May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::play index 0 May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::addQueueItems May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::addQueueItems May 26 00:15:26 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$1 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$1 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$2 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$2 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$3 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$3 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$4 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$4 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$5 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$5 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$6 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$6 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$7 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$7 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$8 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$8 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$9 May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$9 May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$A May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$A May 26 00:15:26 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$B May 26 00:15:26 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$B May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushQueue May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::saveQueue May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::play index undefined May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::updateTrackBlock May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrackBlock May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::startPlaybackTimer May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: info: [1779754526050] ControllerUPNPBrowser::clearAddPlayTrack May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand stop May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand stop took 9 milliseconds May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clear May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand clear took 1 milliseconds May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.188:8200/MediaItems/183.m4a" May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.188:8200/MediaItems/183.m4a" May 26 00:15:26 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:26 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:26 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:26 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:26 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 29ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 28ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 27ms May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand add "http://192.168.1.188:8200/MediaItems/183.m4a" took 4 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand play May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:26 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 12ms May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand play took 11 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 6ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 5ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 4ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 3ms May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:26 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/183.m4a; got HTTP status 403 May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:15:26 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/183.m4a; got HTTP status 403 May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:15:26 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/183.m4a; got HTTP status 403 May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand status took 7 milliseconds May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:26 volumio-main volumio[973]: info: May 26 00:15:26 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:26 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/183.m4a; got HTTP status 403 May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand status took 15 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand clearerror took 11 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand clearerror took 10 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 7 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 7 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 6 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand status took 6 milliseconds May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: 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":"183.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/183.m4a","trackType":"m4a"} May 26 00:15:26 volumio-main volumio[973]: verbose: CURRENT POSITION 0 May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: No code May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: 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":"183.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/183.m4a","trackType":"m4a"} May 26 00:15:26 volumio-main volumio[973]: verbose: CURRENT POSITION 0 May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: No code May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: 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":"183.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/183.m4a","trackType":"m4a"} May 26 00:15:26 volumio-main volumio[973]: verbose: CURRENT POSITION 0 May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: No code May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:26.173Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:26 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:26.173Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:26 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:26.174Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:26 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:26.175Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 62ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 62ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 61ms May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand clearerror took 73 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 71 milliseconds May 26 00:15:26 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 70 milliseconds May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:26 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: 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":"183.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/183.m4a","trackType":"m4a"} May 26 00:15:26 volumio-main volumio[973]: verbose: CURRENT POSITION 0 May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: No code May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: 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":"183.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/183.m4a","trackType":"m4a"} May 26 00:15:26 volumio-main volumio[973]: verbose: CURRENT POSITION 0 May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio[973]: info: No code May 26 00:15:26 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:26 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:26 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:26.247Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 130ms May 26 00:15:26 volumio-main volumio[973]: info: ------------------------------ 121ms May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getQueue May 26 00:15:26 volumio-main volumio[973]: info: CorePlayQueue::getTrack 0 May 26 00:15:26 volumio-main volumio[973]: info: [1779754526732] ControllerPandora::ExpireOldTracks::reaper May 26 00:15:26 volumio-main volumio[973]: info: [1779754526732] [Pandora] ExpireOldTracks::reaper: No victims found: Expiring zero tracks. Don't worry -- Jason will return. May 26 00:15:30 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::ClearQueue May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::clearPlayQueue May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::saveQueue May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushQueue May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::addQueueItems May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::addQueueItems May 26 00:15:30 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$0 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$0 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$1 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$1 May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushQueue May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::saveQueue May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::updateTrackBlock May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrackBlock May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPlay May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::play index 1 May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::addQueueItems May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::addQueueItems May 26 00:15:30 volumio-main volumio[973]: info: Preload queue cleared May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$2 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$2 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$3 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$3 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$4 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$4 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$5 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$5 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$6 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$6 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$7 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$7 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$8 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$8 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$9 May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$9 May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$A May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$A May 26 00:15:30 volumio-main volumio[973]: info: Adding Item to queue: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$B May 26 00:15:30 volumio-main volumio[973]: info: Using cached record of: upnp/http://192.168.1.188:8200/ctl/ContentDir@1$6$5$1$B May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushQueue May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::saveQueue May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::play index undefined May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::updateTrackBlock May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrackBlock May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrack 1 May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::startPlaybackTimer May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrack 1 May 26 00:15:30 volumio-main volumio[973]: info: [1779754530436] ControllerUPNPBrowser::clearAddPlayTrack May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand stop May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand stop took 2 milliseconds May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clear May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand clear took 0 milliseconds May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.188:8200/MediaItems/187.m4a" May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.188:8200/MediaItems/187.m4a" May 26 00:15:30 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:30 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:30 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:30 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:30 volumio-main volumio[973]: error: updateQueue error: null May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 52ms May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 51ms May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 51ms May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 50ms May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand add "http://192.168.1.188:8200/MediaItems/187.m4a" took 1 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand play May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces system playlist update May 26 00:15:30 volumio-main volumio[973]: info: Ignoring MPD Status Update May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 5ms May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand play took 5 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 3ms May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 2ms May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:30 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/187.m4a; got HTTP status 403 May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand status took 4 milliseconds May 26 00:15:30 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/187.m4a; got HTTP status 403 May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand status took 3 milliseconds May 26 00:15:30 volumio-main volumio[973]: error: MPD returned error for command status: Failed to decode http://192.168.1.188:8200/MediaItems/187.m4a; got HTTP status 403 May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand status took 2 milliseconds May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:30 volumio-main volumio[973]: info: May 26 00:15:30 volumio-main volumio[973]: ---------------------------- MPD announces state update: player May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::getState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand clearerror took 5 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand clearerror took 4 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 2 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand status took 2 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand status took 1 milliseconds May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseState May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrack 1 May 26 00:15:30 volumio-main volumio[973]: 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":"187.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/187.m4a","trackType":"m4a"} May 26 00:15:30 volumio-main volumio[973]: verbose: CURRENT POSITION 1 May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: No code May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrack 1 May 26 00:15:30 volumio-main volumio[973]: 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":"187.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/187.m4a","trackType":"m4a"} May 26 00:15:30 volumio-main volumio[973]: verbose: CURRENT POSITION 1 May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: No code May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrack 1 May 26 00:15:30 volumio-main volumio[973]: 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":"187.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/187.m4a","trackType":"m4a"} May 26 00:15:30 volumio-main volumio[973]: verbose: CURRENT POSITION 1 May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: No code May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:30.537Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:30 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:30.539Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:30 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:30.539Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:30 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:30.540Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 34ms May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 34ms May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 38ms May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 52 milliseconds May 26 00:15:30 volumio-main volumio[973]: info: sendMpdCommand playlistinfo took 53 milliseconds May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:30 volumio-main volumio[973]: verbose: ControllerMpd::parseTrackInfo May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrack 1 May 26 00:15:30 volumio-main volumio[973]: 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":"187.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/187.m4a","trackType":"m4a"} May 26 00:15:30 volumio-main volumio[973]: verbose: CURRENT POSITION 1 May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: No code May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: ControllerMpd::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::servicePushState May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: CorePlayQueue::getTrack 1 May 26 00:15:30 volumio-main volumio[973]: 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":"187.m4a","artist":null,"album":null,"uri":"http://192.168.1.188:8200/MediaItems/187.m4a","trackType":"m4a"} May 26 00:15:30 volumio-main volumio[973]: verbose: CURRENT POSITION 1 May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState stateService stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::syncState currentStatus stop May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio[973]: info: No code May 26 00:15:30 volumio-main volumio[973]: info: CoreStateMachine::pushState May 26 00:15:30 volumio-main volumio[973]: info: CoreCommandRouter::volumioPushState May 26 00:15:30 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:30.596Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:30 volumio-main volumio5-onboarding[3784]: time=2026-05-26T00:15:30.597Z level=WARN msg="received unknown player status" component=volumio status="" May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 82ms May 26 00:15:30 volumio-main volumio[973]: info: ------------------------------ 83ms May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:30 volumio-main volumio[973]: info: PeppySpectrum ---peppyspectrum status undefined May 26 00:15:36 volumio-main kernel: hwmon hwmon1: Voltage normalised May 26 00:15:37 volumio-main volumio[973]: info: CoreCommandRouter::volumioGetState May 26 00:15:38 volumio-main kernel: hwmon hwmon1: Undervoltage detected! May 26 00:15:42 volumio-main kernel: hwmon hwmon1: Voltage normalised May 26 00:15:43 volumio-main nmbd[711]: [2026/05/26 00:15:43.302585, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 26 00:15:43 volumio-main nmbd[711]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.157 for name WORKGROUP<1d>. May 26 00:15:43 volumio-main nmbd[711]: This response was from IP 192.168.1.163, reporting an IP address of 192.168.1.163. May 26 00:15:45 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 00:15:45 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 00:15:46 volumio-main kernel: hwmon hwmon1: Undervoltage detected! May 26 00:15:53 volumio-main kernel: hwmon hwmon1: Voltage normalised May 26 00:15:56 volumio-main volumio[973]: info: Enabling plugin fusiondsp May 26 00:15:56 volumio-main volumio[973]: info: Loading plugin "fusiondsp"... May 26 00:15:56 volumio-main volumio[973]: info: Applying required configuration parameters for plugin fusiondsp May 26 00:15:56 volumio-main volumio[973]: info: Preparing to generate the ALSA configuration file May 26 00:15:56 volumio-main volumio[973]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 26 00:15:56 volumio-main volumio[973]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 26 00:15:56 volumio-main volumio[973]: info: Reading ALSA contributions from plugins. May 26 00:15:56 volumio-main volumio[973]: info: Asound.conf file unchanged, so no further update is needed May 26 00:15:56 volumio-main volumio[973]: info: Output device has changed, restarting MPD May 26 00:15:56 volumio-main sudo[10522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 00:15:56 volumio-main sudo[10522]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:56 volumio-main volumio[973]: info: Output device has changed, restarting Shairport Sync May 26 00:15:56 volumio-main sudo[10522]: pam_unix(sudo:session): session closed for user root May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:15:56 volumio-main sudo[10525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 00:15:56 volumio-main sudo[10525]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:56 volumio-main systemd[1]: musicservicesshield.service: Succeeded. May 26 00:15:56 volumio-main systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 26 00:15:56 volumio-main systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 26 00:15:56 volumio-main systemd[1]: Stopping Music Player Daemon... May 26 00:15:56 volumio-main systemd[1]: mpd.service: Succeeded. May 26 00:15:56 volumio-main systemd[1]: Stopped Music Player Daemon. May 26 00:15:56 volumio-main volumio[973]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 00:15:56 volumio-main volumio[973]: info: PLUGIN START: fusiondsp May 26 00:15:56 volumio-main volumio[973]: info: Loading i18n strings for locale en May 26 00:15:56 volumio-main volumio[973]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. May 26 00:15:56 volumio-main volumio[973]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. May 26 00:15:56 volumio-main volumio[973]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 26 00:15:56 volumio-main volumio[973]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: FusionDsp - mixtype--------------------- None May 26 00:15:56 volumio-main volumio[973]: info: Preparing to generate the ALSA configuration file May 26 00:15:56 volumio-main volumio[973]: info: Done. May 26 00:15:56 volumio-main volumio[973]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 26 00:15:56 volumio-main volumio[973]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 26 00:15:56 volumio-main volumio[973]: info: Reading ALSA contributions from plugins. May 26 00:15:56 volumio-main volumio[973]: info: MPD Permissions set May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main systemd[1]: Starting Music Player Daemon... May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: Starting Shairport Sync May 26 00:15:56 volumio-main volumio[973]: info: Asound.conf file unchanged, so no further update is needed May 26 00:15:56 volumio-main volumio[973]: info: Output device has changed, restarting MPD May 26 00:15:56 volumio-main sudo[10537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 00:15:56 volumio-main sudo[10537]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:56 volumio-main systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 26 00:15:56 volumio-main sudo[10530]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 00:15:56 volumio-main volumio[973]: info: Output device has changed, restarting Shairport Sync May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:15:56 volumio-main sudo[10530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:56 volumio-main systemd[1]: shairport-sync.service: Succeeded. May 26 00:15:56 volumio-main systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 26 00:15:56 volumio-main sudo[10540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 00:15:56 volumio-main sudo[10540]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:56 volumio-main sudo[10540]: pam_unix(sudo:session): session closed for user root May 26 00:15:56 volumio-main sudo[10530]: pam_unix(sudo:session): session closed for user root May 26 00:15:56 volumio-main sudo[10542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 00:15:56 volumio-main sudo[10542]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:56 volumio-main systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 26 00:15:56 volumio-main volumio[973]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 00:15:56 volumio-main volumio[973]: info: MPD Permissions set May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:15:56 volumio-main volumio[973]: info: Starting Shairport Sync May 26 00:15:56 volumio-main sudo[10537]: pam_unix(sudo:session): session closed for user root May 26 00:15:56 volumio-main volumio[973]: info: Shairport-Sync Started May 26 00:15:56 volumio-main systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 26 00:15:56 volumio-main systemd[1]: mpd.service: Succeeded. May 26 00:15:56 volumio-main systemd[1]: Stopped Music Player Daemon. May 26 00:15:56 volumio-main sudo[10556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 00:15:56 volumio-main sudo[10556]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:56 volumio-main systemd[1]: Starting Music Player Daemon... May 26 00:15:56 volumio-main systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 26 00:15:56 volumio-main systemd[1]: shairport-sync.service: Succeeded. May 26 00:15:56 volumio-main systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 26 00:15:57 volumio-main systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 26 00:15:57 volumio-main sudo[10556]: pam_unix(sudo:session): session closed for user root May 26 00:15:57 volumio-main volumio[973]: info: Shairport-Sync Started May 26 00:15:57 volumio-main sudo[10559]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 00:15:57 volumio-main sudo[10559]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 00:15:57 volumio-main sudo[10559]: pam_unix(sudo:session): session closed for user root May 26 00:15:57 volumio-main kernel: hwmon hwmon1: Undervoltage detected! May 26 00:15:58 volumio-main mpd[10565]: May 26 00:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 00:15:58 volumio-main volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 00:15:58 volumio-main systemd[1]: Started Music Player Daemon. May 26 00:15:58 volumio-main sudo[10542]: pam_unix(sudo:session): session closed for user root May 26 00:15:58 volumio-main sudo[10525]: pam_unix(sudo:session): session closed for user root May 26 00:15:58 volumio-main volumio[973]: TypeError: Cannot read property 'off' of undefined May 26 00:15:58 volumio-main volumio[973]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 26 00:15:58 volumio-main volumio[973]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 26 00:15:58 volumio-main volumio[973]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 26 00:15:58 volumio-main volumio[973]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 26 00:15:58 volumio-main volumio[973]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) May 26 00:15:58 volumio-main volumio[973]: at Socket.emit (events.js:315:20) May 26 00:15:58 volumio-main volumio[973]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 26 00:15:58 volumio-main volumio[973]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 26 00:15:58 volumio-main volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 00:15:58 volumio-main systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 26 00:15:59 volumio-main volumio[10569]: cset: --> shielding system active with May 26 00:15:59 volumio-main volumio[10569]: cset: "system" cpuset of CPUSPEC(1-3) with 134 tasks running May 26 00:15:59 volumio-main volumio[10569]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 26 00:15:59 volumio-main systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 26 00:15:59 volumio-main sudo[10593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-26 00:14 May 26 00:15:59 volumio-main sudo[10593]: 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"