-- Logs begin at Fri 2026-04-17 15:44:06 UTC, end at Wed 2026-05-27 06:09:30 UTC. -- May 27 06:08:02 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:08:02 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:08:02 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:02 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:02 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:08:03 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:03 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:08:03 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:08:03 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:08:03 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:03 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:03 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:03 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:03 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 116 milliseconds May 27 06:08:03 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:08:03 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:08:03 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:08:03 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 19ms May 27 06:08:03 musical-fidelity volumio[10281]: info: sendMpdCommand status took 12 milliseconds May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:03 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:03 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:03 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:08:03 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:03 musical-fidelity volumio[10281]: info: No code May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 46ms May 27 06:08:03 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:03 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:03 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:03 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:03 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:03 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:03 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:03 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:03 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:03 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:03 musical-fidelity volumio[10281]: info: May 27 06:08:03 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:03 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 06:08:03 musical-fidelity volumio[10281]: info: sendMpdCommand status took 8 milliseconds May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:03 musical-fidelity volumio[10281]: info: sendMpdCommand clearerror took 7 milliseconds May 27 06:08:03 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 6 milliseconds May 27 06:08:03 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:03 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:03 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:04 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:04 musical-fidelity volumio[10281]: 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":"Roundtable Rival","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997844&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865682&hmac=QhMFn5V8yZQfxMB8BvcoNQB0VAU","trackType":"qobuz"} May 27 06:08:04 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:04 musical-fidelity volumio[10281]: info: No code May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:04 musical-fidelity volumio[10281]: info: ------------------------------ 58ms May 27 06:08:04 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:04 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:04 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:14 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:08:14 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:08:14 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:08:14 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:08:14 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:14 musical-fidelity volumio[10281]: info: May 27 06:08:14 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:14 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:14 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:08:14 musical-fidelity volumio[10281]: info: ------------------------------ 5ms May 27 06:08:14 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:08:14 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:08:14 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:14 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:08:15 musical-fidelity volumio[10281]: info: May 27 06:08:15 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:15 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:15 musical-fidelity volumio[10281]: info: May 27 06:08:15 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:15 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:15 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:15 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:15 musical-fidelity volumio[10281]: info: May 27 06:08:15 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:15 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:15 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:15 musical-fidelity volumio[10281]: info: May 27 06:08:15 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:15 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:15 musical-fidelity volumio[10281]: info: May 27 06:08:15 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:15 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:15 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:15 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:15 musical-fidelity volumio[10281]: info: May 27 06:08:15 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:15 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:15 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:17 musical-fidelity volumio[10281]: info: May 27 06:08:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:17 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:17 musical-fidelity volumio[10281]: info: May 27 06:08:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:17 musical-fidelity volumio[10281]: info: sendMpdCommand status took 61 milliseconds May 27 06:08:17 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:17 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:08:17 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:17 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:17 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:17 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mirror Haus","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997840&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865694&hmac=_7QGXpo7Uu820PxAT2maxy9Lxzw","trackType":"qobuz"} May 27 06:08:17 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:17 musical-fidelity volumio[10281]: info: ------------------------------ 90ms May 27 06:08:17 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 06:08:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:17 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:17 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:17 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378,"duration":236,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2027 Kbps","isStreaming":false,"title":"Mirror Haus","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997840&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865694&hmac=_7QGXpo7Uu820PxAT2maxy9Lxzw","trackType":"qobuz"} May 27 06:08:17 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:08:17 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:17 musical-fidelity volumio[10281]: info: ------------------------------ 72ms May 27 06:08:17 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:17 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:17 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:27 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:08:27 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:27 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:08:27 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:08:27 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:27 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:27 musical-fidelity volumio[10281]: info: May 27 06:08:27 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:27 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:27 musical-fidelity volumio[10281]: info: May 27 06:08:27 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:27 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:27 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:27 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 55 milliseconds May 27 06:08:27 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:08:27 musical-fidelity volumio[10281]: info: ------------------------------ 16ms May 27 06:08:27 musical-fidelity volumio[10281]: info: sendMpdCommand status took 7 milliseconds May 27 06:08:27 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:27 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:27 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:08:27 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:27 musical-fidelity volumio[10281]: info: No code May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:27 musical-fidelity volumio[10281]: info: ------------------------------ 61ms May 27 06:08:27 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:08:27 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:08:27 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:27 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:08:27 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:27 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:27 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:28 musical-fidelity volumio[10281]: info: May 27 06:08:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:28 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:28 musical-fidelity volumio[10281]: info: May 27 06:08:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:28 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:28 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:28 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:28 musical-fidelity volumio[10281]: info: May 27 06:08:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:28 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:28 musical-fidelity volumio[10281]: info: May 27 06:08:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:28 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:28 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:28 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:28 musical-fidelity volumio[10281]: info: May 27 06:08:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:28 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:28 musical-fidelity volumio[10281]: info: May 27 06:08:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:28 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:28 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:28 musical-fidelity volumio[10281]: info: ------------------------------ 7ms May 27 06:08:29 musical-fidelity volumio[10281]: info: May 27 06:08:29 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:29 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:29 musical-fidelity volumio[10281]: info: May 27 06:08:29 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:29 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:08:29 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:29 musical-fidelity volumio[10281]: info: sendMpdCommand status took 5 milliseconds May 27 06:08:29 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:29 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:29 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:29 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"Beyond the Veil","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997839&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865706&hmac=0uwmYQxlDHBlxOr17TG4MbZLMQs","trackType":"qobuz"} May 27 06:08:29 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:29 musical-fidelity volumio[10281]: info: ------------------------------ 32ms May 27 06:08:29 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 22 milliseconds May 27 06:08:29 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:29 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:29 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:29 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2 Kbps","isStreaming":false,"title":"Beyond the Veil","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997839&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865706&hmac=0uwmYQxlDHBlxOr17TG4MbZLMQs","trackType":"qobuz"} May 27 06:08:29 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:08:29 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:29 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:29 musical-fidelity volumio[10281]: info: ------------------------------ 57ms May 27 06:08:29 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:29 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:29 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:37 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:08:38 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:08:38 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:08:38 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:38 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:08:38 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:08:38 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:08:38 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:08:38 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:38 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:38 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:38 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:38 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:38 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 105 milliseconds May 27 06:08:38 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:08:38 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:08:38 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:08:38 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 5ms May 27 06:08:38 musical-fidelity volumio[10281]: info: sendMpdCommand status took 3 milliseconds May 27 06:08:38 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:38 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:38 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:38 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:08:38 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:38 musical-fidelity volumio[10281]: info: No code May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:38 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 38ms May 27 06:08:38 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:38 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:38 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:38 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:38 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:38 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:38 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:38 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:38 musical-fidelity volumio[10281]: info: May 27 06:08:38 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:38 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:38 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:40 musical-fidelity volumio[10281]: info: May 27 06:08:40 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:40 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:40 musical-fidelity volumio[10281]: info: May 27 06:08:40 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:40 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:40 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:40 musical-fidelity volumio[10281]: info: sendMpdCommand status took 3 milliseconds May 27 06:08:40 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:40 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:40 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:40 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3026 Kbps","isStreaming":false,"title":"V-Pop","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997841&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865717&hmac=RQGJ03tTDusSQ7ewNE7fU0QksLU","trackType":"qobuz"} May 27 06:08:40 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:40 musical-fidelity volumio[10281]: info: ------------------------------ 21ms May 27 06:08:40 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 13 milliseconds May 27 06:08:40 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:40 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:40 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:40 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3026 Kbps","isStreaming":false,"title":"V-Pop","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997841&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865717&hmac=RQGJ03tTDusSQ7ewNE7fU0QksLU","trackType":"qobuz"} May 27 06:08:40 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:08:40 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:40 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:40 musical-fidelity volumio[10281]: info: ------------------------------ 55ms May 27 06:08:40 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:40 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:40 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:44 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:08:44 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:44 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:08:44 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:08:44 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:44 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:44 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 42 milliseconds May 27 06:08:44 musical-fidelity volumio[10281]: info: May 27 06:08:44 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:44 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:44 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:44 musical-fidelity volumio[10281]: info: May 27 06:08:44 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:44 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:44 musical-fidelity volumio[10281]: info: sendMpdCommand status took 2 milliseconds May 27 06:08:44 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:44 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:44 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:08:44 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:44 musical-fidelity volumio[10281]: info: No code May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:44 musical-fidelity volumio[10281]: info: ------------------------------ 37ms May 27 06:08:44 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:08:44 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:08:44 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:44 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:08:44 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:08:44 musical-fidelity volumio[10281]: info: ------------------------------ 40ms May 27 06:08:44 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:44 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:44 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:45 musical-fidelity volumio[10281]: info: May 27 06:08:45 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:45 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:45 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:45 musical-fidelity volumio[10281]: info: May 27 06:08:45 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:45 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:45 musical-fidelity volumio[10281]: info: May 27 06:08:45 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:45 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:45 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:45 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:45 musical-fidelity volumio[10281]: info: May 27 06:08:45 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:45 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:45 musical-fidelity volumio[10281]: info: May 27 06:08:45 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:45 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:45 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:45 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:45 musical-fidelity volumio[10281]: info: May 27 06:08:45 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:45 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:45 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:47 musical-fidelity volumio[10281]: info: May 27 06:08:47 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:47 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:47 musical-fidelity volumio[10281]: info: May 27 06:08:47 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:47 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:47 musical-fidelity volumio[10281]: info: sendMpdCommand status took 2 milliseconds May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:47 musical-fidelity volumio[10281]: info: sendMpdCommand status took 3 milliseconds May 27 06:08:47 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:47 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:47 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:47 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":165,"duration":281,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2665 Kbps","isStreaming":false,"title":"Shatter Me","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997842&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865723&hmac=qayVbjjpUlboBtd7_6cdLWahtnA","trackType":"qobuz"} May 27 06:08:47 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:47 musical-fidelity volumio[10281]: info: ------------------------------ 22ms May 27 06:08:47 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 16 milliseconds May 27 06:08:47 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:47 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:47 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:47 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":281,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2629 Kbps","isStreaming":false,"title":"Shatter Me","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997842&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865723&hmac=qayVbjjpUlboBtd7_6cdLWahtnA","trackType":"qobuz"} May 27 06:08:47 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:08:47 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:47 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:47 musical-fidelity volumio[10281]: info: ------------------------------ 61ms May 27 06:08:47 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:47 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:47 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:50 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:08:50 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:50 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:08:50 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:08:50 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:50 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:50 musical-fidelity volumio[10281]: info: May 27 06:08:50 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:50 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:50 musical-fidelity volumio[10281]: info: May 27 06:08:50 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:50 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:50 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:50 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 49 milliseconds May 27 06:08:50 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:08:50 musical-fidelity volumio[10281]: info: ------------------------------ 6ms May 27 06:08:50 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:08:50 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:50 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:50 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:08:50 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:50 musical-fidelity volumio[10281]: info: No code May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:50 musical-fidelity volumio[10281]: info: ------------------------------ 53ms May 27 06:08:50 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:08:50 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:08:50 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:50 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:08:50 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:50 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:50 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:51 musical-fidelity volumio[10281]: info: May 27 06:08:51 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:51 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:51 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:51 musical-fidelity volumio[10281]: info: May 27 06:08:51 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:51 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:51 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:51 musical-fidelity volumio[10281]: info: May 27 06:08:51 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:51 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:51 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:51 musical-fidelity volumio[10281]: info: May 27 06:08:51 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:51 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:51 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:08:51 musical-fidelity volumio[10281]: info: May 27 06:08:51 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:51 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:51 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:51 musical-fidelity volumio[10281]: info: May 27 06:08:51 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:51 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:51 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:08:53 musical-fidelity volumio[10281]: info: May 27 06:08:53 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:53 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:53 musical-fidelity volumio[10281]: info: May 27 06:08:53 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:53 musical-fidelity volumio[10281]: info: sendMpdCommand status took 6 milliseconds May 27 06:08:53 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:53 musical-fidelity volumio[10281]: info: sendMpdCommand status took 2 milliseconds May 27 06:08:53 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:53 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:53 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:53 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":207,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heist","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997843&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865729&hmac=BnOAo1baA00W5swakjHhQQRlU4U","trackType":"qobuz"} May 27 06:08:53 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:53 musical-fidelity volumio[10281]: info: ------------------------------ 32ms May 27 06:08:53 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 21 milliseconds May 27 06:08:53 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:08:53 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:53 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:08:53 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":426,"duration":207,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3011 Kbps","isStreaming":false,"title":"Heist","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997843&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865729&hmac=BnOAo1baA00W5swakjHhQQRlU4U","trackType":"qobuz"} May 27 06:08:53 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:08:53 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:53 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:53 musical-fidelity volumio[10281]: info: ------------------------------ 54ms May 27 06:08:53 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:53 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:53 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:59 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:08:59 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:08:59 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:08:59 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:59 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:08:59 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:08:59 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:08:59 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:08:59 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:59 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:59 musical-fidelity volumio[10281]: info: May 27 06:08:59 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:08:59 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:08:59 musical-fidelity volumio[10281]: info: May 27 06:08:59 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:08:59 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:08:59 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:08:59 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 58 milliseconds May 27 06:08:59 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:08:59 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:08:59 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:08:59 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:08:59 musical-fidelity volumio[10281]: info: ------------------------------ 7ms May 27 06:08:59 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:08:59 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:08:59 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:59 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:08:59 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:08:59 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:59 musical-fidelity volumio[10281]: info: No code May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:08:59 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:08:59 musical-fidelity volumio[10281]: info: ------------------------------ 45ms May 27 06:08:59 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:59 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:08:59 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:00 musical-fidelity volumio[10281]: info: May 27 06:09:00 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:00 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:00 musical-fidelity volumio[10281]: info: May 27 06:09:00 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:00 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:00 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:00 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:00 musical-fidelity volumio[10281]: info: May 27 06:09:00 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:00 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:00 musical-fidelity volumio[10281]: info: May 27 06:09:00 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:00 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:00 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:00 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:00 musical-fidelity volumio[10281]: info: May 27 06:09:00 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:00 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:00 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:00 musical-fidelity volumio[10281]: info: May 27 06:09:00 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:00 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:00 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:04 musical-fidelity volumio[10281]: info: May 27 06:09:04 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:04 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:04 musical-fidelity volumio[10281]: info: May 27 06:09:04 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:04 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:04 musical-fidelity volumio[10281]: info: sendMpdCommand status took 3 milliseconds May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:04 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:09:04 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:04 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:04 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:04 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":165,"duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2218 Kbps","isStreaming":false,"title":"Roundtable Rival","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997844&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865738&hmac=U0OrTVuMGt3z39uic_t7gukqtsw","trackType":"qobuz"} May 27 06:09:04 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:04 musical-fidelity volumio[10281]: info: ------------------------------ 31ms May 27 06:09:04 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 24 milliseconds May 27 06:09:04 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:04 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:04 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:04 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":282,"duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2218 Kbps","isStreaming":false,"title":"Roundtable Rival","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997844&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865738&hmac=U0OrTVuMGt3z39uic_t7gukqtsw","trackType":"qobuz"} May 27 06:09:04 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:09:04 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:04 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:04 musical-fidelity volumio[10281]: info: ------------------------------ 61ms May 27 06:09:04 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:04 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:04 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:10 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:09:10 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:09:10 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:09:10 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:10 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:09:10 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:09:10 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:09:10 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:09:10 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:10 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:10 musical-fidelity volumio[10281]: info: May 27 06:09:10 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:10 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:10 musical-fidelity volumio[10281]: info: May 27 06:09:10 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:10 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:10 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:10 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 117 milliseconds May 27 06:09:10 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:09:10 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:09:10 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:09:10 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:09:10 musical-fidelity volumio[10281]: info: ------------------------------ 5ms May 27 06:09:10 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:09:10 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:10 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:10 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:10 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:09:10 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:10 musical-fidelity volumio[10281]: info: No code May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:10 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:10 musical-fidelity volumio[10281]: info: ------------------------------ 36ms May 27 06:09:10 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:10 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:10 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:11 musical-fidelity volumio[10281]: info: May 27 06:09:11 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:11 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:11 musical-fidelity volumio[10281]: info: ------------------------------ 1ms May 27 06:09:11 musical-fidelity volumio[10281]: info: May 27 06:09:11 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:11 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:11 musical-fidelity volumio[10281]: info: May 27 06:09:11 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:11 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:11 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:11 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:11 musical-fidelity volumio[10281]: info: May 27 06:09:11 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:11 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:11 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:11 musical-fidelity volumio[10281]: info: May 27 06:09:11 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:11 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:11 musical-fidelity volumio[10281]: info: May 27 06:09:11 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:11 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:11 musical-fidelity volumio[10281]: info: ------------------------------ 4ms May 27 06:09:11 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:13 musical-fidelity volumio[10281]: info: May 27 06:09:13 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:13 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:13 musical-fidelity volumio[10281]: info: May 27 06:09:13 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:13 musical-fidelity volumio[10281]: info: sendMpdCommand status took 2 milliseconds May 27 06:09:13 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:13 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:09:13 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:13 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:13 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:13 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48,"duration":220,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1801 Kbps","isStreaming":false,"title":"Night Vision","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997845&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865750&hmac=l5OyeT9lSm_RMUN4YN5kZZgwdA0","trackType":"qobuz"} May 27 06:09:13 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:13 musical-fidelity volumio[10281]: info: ------------------------------ 36ms May 27 06:09:13 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 28 milliseconds May 27 06:09:13 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:13 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:13 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:13 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":220,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2163 Kbps","isStreaming":false,"title":"Night Vision","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997845&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865750&hmac=l5OyeT9lSm_RMUN4YN5kZZgwdA0","trackType":"qobuz"} May 27 06:09:13 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:09:13 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:13 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:13 musical-fidelity volumio[10281]: info: ------------------------------ 64ms May 27 06:09:13 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:13 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:13 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:16 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:09:16 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:09:16 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:16 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:09:16 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:16 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:09:16 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:09:16 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:09:16 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:16 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:09:16 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:16 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:16 musical-fidelity volumio[10281]: info: May 27 06:09:16 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:16 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:16 musical-fidelity volumio[10281]: info: May 27 06:09:16 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:16 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:16 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:16 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 102 milliseconds May 27 06:09:16 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:09:16 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:16 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:09:17 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:09:17 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:09:17 musical-fidelity volumio[10281]: info: sendMpdCommand status took 4 milliseconds May 27 06:09:17 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:17 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:17 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:17 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:09:17 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:17 musical-fidelity volumio[10281]: info: No code May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:17 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:17 musical-fidelity volumio[10281]: info: ------------------------------ 29ms May 27 06:09:17 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:17 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:17 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:17 musical-fidelity volumio[10281]: info: May 27 06:09:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:17 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:17 musical-fidelity volumio[10281]: info: May 27 06:09:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:17 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:17 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:17 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:17 musical-fidelity volumio[10281]: info: May 27 06:09:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:17 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:17 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:17 musical-fidelity volumio[10281]: info: May 27 06:09:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:17 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:17 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:17 musical-fidelity volumio[10281]: info: May 27 06:09:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:17 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:17 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:17 musical-fidelity volumio[10281]: info: May 27 06:09:17 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:17 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:17 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:19 musical-fidelity volumio[10281]: info: May 27 06:09:19 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:19 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:19 musical-fidelity volumio[10281]: info: May 27 06:09:19 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:19 musical-fidelity volumio[10281]: info: sendMpdCommand status took 8 milliseconds May 27 06:09:19 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:19 musical-fidelity volumio[10281]: info: sendMpdCommand status took 3 milliseconds May 27 06:09:19 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:19 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:19 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:19 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Flight","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997846&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865756&hmac=6i0R14nDW-ahp-Fkfr0H8Czn22g","trackType":"qobuz"} May 27 06:09:19 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:19 musical-fidelity volumio[10281]: info: ------------------------------ 34ms May 27 06:09:19 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 06:09:19 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:19 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:19 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:19 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2530 Kbps","isStreaming":false,"title":"Take Flight","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997846&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865756&hmac=6i0R14nDW-ahp-Fkfr0H8Czn22g","trackType":"qobuz"} May 27 06:09:19 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:09:19 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:19 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:19 musical-fidelity volumio[10281]: info: ------------------------------ 58ms May 27 06:09:19 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:19 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:19 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:24 musical-fidelity volumio[10281]: info: Clearing queue after UPNP request May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::ClearQueue May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::stop May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 06:09:25 musical-fidelity volumio[10281]: verbose: UNSET VOLATILE May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::stPlaybackTimer May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::updateTrackBlock May 27 06:09:25 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrackBlock May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::serviceStop May 27 06:09:25 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:25 musical-fidelity volumio[10281]: info: ControllerMpd::stop May 27 06:09:25 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand stop May 27 06:09:25 musical-fidelity volumio[10281]: info: CorePlayQueue::clearPlayQueue May 27 06:09:25 musical-fidelity volumio[10281]: info: CorePlayQueue::saveQueue May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushQueue May 27 06:09:25 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:25 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:25 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:25 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:25 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:25 musical-fidelity volumio[10281]: info: sendMpdCommand stop took 78 milliseconds May 27 06:09:25 musical-fidelity volumio[10281]: info: Starting UPNP Playback May 27 06:09:25 musical-fidelity volumio[10281]: info: Preparing playback through UPNP May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioGetState May 27 06:09:25 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 06:09:25 musical-fidelity volumio[10281]: error: updateQueue error: null May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 8ms May 27 06:09:25 musical-fidelity volumio[10281]: info: sendMpdCommand status took 6 milliseconds May 27 06:09:25 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:25 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:25 musical-fidelity volumio[10281]: info: CorePlayQueue::getTrack 0 May 27 06:09:25 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 06:09:25 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService stop May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:25 musical-fidelity volumio[10281]: info: No code May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:25 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 47ms May 27 06:09:25 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:25 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:25 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:25 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:25 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:25 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:25 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 3ms May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:25 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:25 musical-fidelity volumio[10281]: info: May 27 06:09:25 musical-fidelity volumio[10281]: ---------------------------- MPD announces system playlist update May 27 06:09:25 musical-fidelity volumio[10281]: info: Ignoring MPD Status Update May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:25 musical-fidelity volumio[10281]: info: ------------------------------ 2ms May 27 06:09:28 musical-fidelity volumio[10281]: info: May 27 06:09:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:28 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:28 musical-fidelity volumio[10281]: info: May 27 06:09:28 musical-fidelity volumio[10281]: ---------------------------- MPD announces state update: player May 27 06:09:28 musical-fidelity volumio[10281]: info: sendMpdCommand status took 52 milliseconds May 27 06:09:28 musical-fidelity volumio[10281]: info: ControllerMpd::getState May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand status May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:28 musical-fidelity volumio[10281]: info: sendMpdCommand status took 3 milliseconds May 27 06:09:28 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseState May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:28 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:28 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:28 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"533 Kbps","isStreaming":false,"title":"Forgotten Voyage","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997854&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865764&hmac=oMFyvUcPQY7STiYIXfVZT89Foqw","trackType":"qobuz"} May 27 06:09:28 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus stop May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:28 musical-fidelity volumio[10281]: info: ------------------------------ 75ms May 27 06:09:28 musical-fidelity volumio[10281]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 06:09:28 musical-fidelity volumio[10281]: verbose: ControllerMpd::parseTrackInfo May 27 06:09:28 musical-fidelity volumio[10281]: info: ControllerMpd::pushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::servicePushState May 27 06:09:28 musical-fidelity volumio[10281]: verbose: In UPNP mode May 27 06:09:28 musical-fidelity volumio[10281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"533 Kbps","isStreaming":false,"title":"Forgotten Voyage","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=357997854&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1779865764&hmac=oMFyvUcPQY7STiYIXfVZT89Foqw","trackType":"qobuz"} May 27 06:09:28 musical-fidelity volumio[10281]: verbose: CURRENT POSITION 0 May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState stateService play May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreStateMachine::syncState currentStatus play May 27 06:09:28 musical-fidelity volumio[10281]: info: Received an update from plugin. extracting info from payload May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreStateMachine::pushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::volumioPushState May 27 06:09:28 musical-fidelity volumio[10281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 06:09:28 musical-fidelity volumio[10281]: info: ------------------------------ 54ms May 27 06:09:28 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:28 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:28 musical-fidelity volumio[10281]: info: Updating RAAT Signal Path May 27 06:09:29 musical-fidelity volumio[10281]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 06:09:29 musical-fidelity volumio[10281]: { Error: connect ETIMEDOUT 31.13.75.12:443 May 27 06:09:29 musical-fidelity volumio[10281]: at Object._errnoException (util.js:1022:11) May 27 06:09:29 musical-fidelity volumio[10281]: at _exceptionWithHostPort (util.js:1044:20) May 27 06:09:29 musical-fidelity volumio[10281]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 27 06:09:29 musical-fidelity volumio[10281]: code: 'ETIMEDOUT', May 27 06:09:29 musical-fidelity volumio[10281]: errno: 'ETIMEDOUT', May 27 06:09:29 musical-fidelity volumio[10281]: syscall: 'connect', May 27 06:09:29 musical-fidelity volumio[10281]: address: '31.13.75.12', May 27 06:09:29 musical-fidelity volumio[10281]: port: 443 } May 27 06:09:29 musical-fidelity volumio[10281]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 06:09:30 musical-fidelity sudo[13195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-27 06:08 May 27 06:09:30 musical-fidelity sudo[13195]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"