-- Logs begin at Wed 2022-07-13 14:55:24 UTC, end at Tue 2025-12-23 05:58:42 UTC. -- Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Clearing queue after UPNP request Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::ClearQueue Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::stop Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 05:57:07 musical-fidelity volumio[6537]: verbose: UNSET VOLATILE Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::stPlaybackTimer Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::updateTrackBlock Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrackBlock Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::serviceStop Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:07 musical-fidelity volumio[6537]: info: ControllerMpd::stop Dec 23 05:57:07 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CorePlayQueue::clearPlayQueue Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CorePlayQueue::saveQueue Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushQueue Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Dec 23 05:57:07 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Dec 23 05:57:07 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:07 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:07 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:07 musical-fidelity volumio[6537]: info: sendMpdCommand stop took 40 milliseconds Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Starting UPNP Playback Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Preparing playback through UPNP Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioGetState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 05:57:07 musical-fidelity volumio[6537]: error: updateQueue error: null Dec 23 05:57:07 musical-fidelity volumio[6537]: info: ------------------------------ 16ms Dec 23 05:57:07 musical-fidelity volumio[6537]: info: sendMpdCommand status took 14 milliseconds Dec 23 05:57:07 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:07 musical-fidelity volumio[6537]: 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} Dec 23 05:57:07 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService stop Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:07 musical-fidelity volumio[6537]: info: No code Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:07 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:07 musical-fidelity volumio[6537]: info: ------------------------------ 50ms Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:07 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:07 musical-fidelity volumio[6537]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B0%B7%E6%9D%91%E6%96%B0%E5%8F%B8/%E6%98%B4%20-%E3%81%99%E3%81%B0%E3%82%8B-/2ec294dd-0308-4c15-98be-c7f74054b764.png' Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Dec 23 05:57:08 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: ------------------------------ 5ms Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Dec 23 05:57:08 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Dec 23 05:57:08 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: ------------------------------ 6ms Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Dec 23 05:57:08 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: ------------------------------ 4ms Dec 23 05:57:08 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Dec 23 05:57:08 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Dec 23 05:57:08 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:08 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:10 musical-fidelity volumio[6537]: info: Dec 23 05:57:10 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:10 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:10 musical-fidelity volumio[6537]: info: Dec 23 05:57:10 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:10 musical-fidelity volumio[6537]: info: sendMpdCommand status took 53 milliseconds Dec 23 05:57:10 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:57:10 musical-fidelity volumio[6537]: info: sendMpdCommand status took 4 milliseconds Dec 23 05:57:10 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:57:10 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nocturne No. 12 in E Major, H. 13 “Nocturne Caractéristique: Noontide”","artist":"Alice Sara Ott","album":"John Field: Complete Nocturnes","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=304206851&fmt=27&profile=raw&app_id=195822208&cid=3599692&etsp=1766473027&hmac=ejWM25IGe0cPIRj8dT_Gu6k6c1o","trackType":"qobuz"} Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:10 musical-fidelity volumio[6537]: info: ------------------------------ 81ms Dec 23 05:57:10 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:57:10 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":213,"duration":272,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4515 Kbps","isStreaming":false,"title":"Nocturne No. 12 in E Major, H. 13 “Nocturne Caractéristique: Noontide”","artist":"Alice Sara Ott","album":"John Field: Complete Nocturnes","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=304206851&fmt=27&profile=raw&app_id=195822208&cid=3599692&etsp=1766473027&hmac=ejWM25IGe0cPIRj8dT_Gu6k6c1o","trackType":"qobuz"} Dec 23 05:57:10 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:57:10 musical-fidelity volumio[6537]: info: Received an update from plugin. extracting info from payload Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:10 musical-fidelity volumio[6537]: info: ------------------------------ 64ms Dec 23 05:57:10 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:10 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:10 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:13 musical-fidelity volumio[6537]: info: Dec 23 05:57:13 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:13 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:13 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:13 musical-fidelity volumio[6537]: info: sendMpdCommand status took 4 milliseconds Dec 23 05:57:13 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:13 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:57:13 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 05:57:13 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:57:13 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:13 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:13 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:57:13 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2544,"duration":272,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4918 Kbps","isStreaming":false,"title":"Nocturne No. 12 in E Major, H. 13 “Nocturne Caractéristique: Noontide”","artist":"Alice Sara Ott","album":"John Field: Complete Nocturnes","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=304206851&fmt=27&profile=raw&app_id=195822208&cid=3599692&etsp=1766473027&hmac=ejWM25IGe0cPIRj8dT_Gu6k6c1o","trackType":"qobuz"} Dec 23 05:57:13 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:13 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService pause Dec 23 05:57:13 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:57:13 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:13 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:13 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:13 musical-fidelity volumio[6537]: info: CoreStateMachine::stPlaybackTimer Dec 23 05:57:13 musical-fidelity volumio[6537]: info: ------------------------------ 21ms Dec 23 05:57:13 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:14 musical-fidelity volumio[6537]: info: Dec 23 05:57:14 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:14 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:14 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:14 musical-fidelity volumio[6537]: info: sendMpdCommand status took 1 milliseconds Dec 23 05:57:14 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:14 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:57:14 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 05:57:14 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:57:14 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:14 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:57:14 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2808,"duration":272,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4846 Kbps","isStreaming":false,"title":"Nocturne No. 12 in E Major, H. 13 “Nocturne Caractéristique: Noontide”","artist":"Alice Sara Ott","album":"John Field: Complete Nocturnes","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=304206851&fmt=27&profile=raw&app_id=195822208&cid=3599692&etsp=1766473027&hmac=ejWM25IGe0cPIRj8dT_Gu6k6c1o","trackType":"qobuz"} Dec 23 05:57:14 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:57:14 musical-fidelity volumio[6537]: info: Received an update from plugin. extracting info from payload Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:14 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:14 musical-fidelity volumio[6537]: info: ------------------------------ 44ms Dec 23 05:57:14 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:14 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Clearing queue after UPNP request Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::ClearQueue Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::stop Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 05:57:51 musical-fidelity volumio[6537]: verbose: UNSET VOLATILE Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::stPlaybackTimer Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::updateTrackBlock Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrackBlock Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::serviceStop Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: info: ControllerMpd::stop Dec 23 05:57:51 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::clearPlayQueue Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::saveQueue Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushQueue Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Dec 23 05:57:51 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Dec 23 05:57:51 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:51 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:51 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:51 musical-fidelity volumio[6537]: info: sendMpdCommand stop took 59 milliseconds Dec 23 05:57:51 musical-fidelity volumio[6537]: error: updateQueue error: null Dec 23 05:57:51 musical-fidelity volumio[6537]: info: ------------------------------ 5ms Dec 23 05:57:51 musical-fidelity volumio[6537]: info: sendMpdCommand status took 3 milliseconds Dec 23 05:57:51 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: 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} Dec 23 05:57:51 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService stop Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:51 musical-fidelity volumio[6537]: info: No code Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:51 musical-fidelity volumio[6537]: info: ------------------------------ 36ms Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Starting UPNP Playback Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Preparing playback through UPNP Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioGetState Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:57:51 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:51 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Dec 23 05:57:52 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Dec 23 05:57:52 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Dec 23 05:57:52 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Dec 23 05:57:52 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Dec 23 05:57:52 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Dec 23 05:57:52 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:57:52 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:57:53 musical-fidelity volumio[6537]: info: Dec 23 05:57:53 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:53 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:53 musical-fidelity volumio[6537]: info: Dec 23 05:57:53 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:57:53 musical-fidelity volumio[6537]: info: sendMpdCommand status took 56 milliseconds Dec 23 05:57:53 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:57:53 musical-fidelity volumio[6537]: info: sendMpdCommand status took 3 milliseconds Dec 23 05:57:53 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:57:53 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Swansong","artist":"Paavo Järvi","album":"Arvo Pärt: Credo","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=351562975&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473070&hmac=Cjw0dNWxz5RNaKCVYr4_OGHQkm0","trackType":"qobuz"} Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:53 musical-fidelity volumio[6537]: info: ------------------------------ 78ms Dec 23 05:57:53 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:57:53 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":344,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1071 Kbps","isStreaming":false,"title":"Swansong","artist":"Paavo Järvi","album":"Arvo Pärt: Credo","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=351562975&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473070&hmac=Cjw0dNWxz5RNaKCVYr4_OGHQkm0","trackType":"qobuz"} Dec 23 05:57:53 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:57:53 musical-fidelity volumio[6537]: info: Received an update from plugin. extracting info from payload Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:57:53 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:57:53 musical-fidelity volumio[6537]: info: ------------------------------ 49ms Dec 23 05:57:53 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:53 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:53 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:57:53 musical-fidelity volumio[6537]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alice%20Sara%20Ott/John%20Field%3A%20Complete%20Nocturnes/5336996c-fc2f-416e-b4ea-e76b5fc59f34.jpg' Dec 23 05:58:03 musical-fidelity volumio[6537]: info: Clearing queue after UPNP request Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::ClearQueue Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::stop Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 05:58:04 musical-fidelity volumio[6537]: verbose: UNSET VOLATILE Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::stPlaybackTimer Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::updateTrackBlock Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrackBlock Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::serviceStop Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ControllerMpd::stop Dec 23 05:58:04 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CorePlayQueue::clearPlayQueue Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CorePlayQueue::saveQueue Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushQueue Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:04 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:04 musical-fidelity volumio[6537]: info: sendMpdCommand stop took 138 milliseconds Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Starting UPNP Playback Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Preparing playback through UPNP Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioGetState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 05:58:04 musical-fidelity volumio[6537]: error: updateQueue error: null Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 5ms Dec 23 05:58:04 musical-fidelity volumio[6537]: info: sendMpdCommand status took 4 milliseconds Dec 23 05:58:04 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:04 musical-fidelity volumio[6537]: 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} Dec 23 05:58:04 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService stop Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:04 musical-fidelity volumio[6537]: info: No code Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:04 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 30ms Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 2ms Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 10ms Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 2ms Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Dec 23 05:58:04 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:04 musical-fidelity volumio[6537]: info: ------------------------------ 2ms Dec 23 05:58:05 musical-fidelity volumio[6537]: info: Dec 23 05:58:05 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:05 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:05 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:06 musical-fidelity volumio[6537]: info: sendMpdCommand status took 52 milliseconds Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:06 musical-fidelity volumio[6537]: info: Dec 23 05:58:06 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:06 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:06 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 23 05:58:06 musical-fidelity volumio[6537]: info: sendMpdCommand status took 5 milliseconds Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:06 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cascade I","artist":"Julius Asal","album":"SIENA TAPES","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=328245381&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473083&hmac=gY70os7XPrmizQjeyzgBl2UEagc","trackType":"qobuz"} Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:06 musical-fidelity volumio[6537]: info: ------------------------------ 78ms Dec 23 05:58:06 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:06 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":188,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2158 Kbps","isStreaming":false,"title":"Cascade I","artist":"Julius Asal","album":"SIENA TAPES","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=328245381&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473083&hmac=gY70os7XPrmizQjeyzgBl2UEagc","trackType":"qobuz"} Dec 23 05:58:06 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:58:06 musical-fidelity volumio[6537]: info: Received an update from plugin. extracting info from payload Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:06 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:06 musical-fidelity volumio[6537]: info: ------------------------------ 48ms Dec 23 05:58:06 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:06 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:06 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:09 musical-fidelity volumio[6537]: info: Dec 23 05:58:09 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:09 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:09 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:09 musical-fidelity volumio[6537]: info: sendMpdCommand status took 1 milliseconds Dec 23 05:58:09 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:09 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:09 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 05:58:09 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:09 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:09 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:09 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:09 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3087,"duration":188,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2580 Kbps","isStreaming":false,"title":"Cascade I","artist":"Julius Asal","album":"SIENA TAPES","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=328245381&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473083&hmac=gY70os7XPrmizQjeyzgBl2UEagc","trackType":"qobuz"} Dec 23 05:58:09 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:09 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService pause Dec 23 05:58:09 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:58:09 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:09 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:09 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:09 musical-fidelity volumio[6537]: info: CoreStateMachine::stPlaybackTimer Dec 23 05:58:09 musical-fidelity volumio[6537]: info: ------------------------------ 22ms Dec 23 05:58:09 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:10 musical-fidelity volumio[6537]: info: Dec 23 05:58:10 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:10 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:10 musical-fidelity volumio[6537]: info: sendMpdCommand status took 2 milliseconds Dec 23 05:58:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:10 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 05:58:10 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:10 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:10 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:10 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3583,"duration":188,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2636 Kbps","isStreaming":false,"title":"Cascade I","artist":"Julius Asal","album":"SIENA TAPES","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=328245381&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473083&hmac=gY70os7XPrmizQjeyzgBl2UEagc","trackType":"qobuz"} Dec 23 05:58:10 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:58:10 musical-fidelity volumio[6537]: info: Received an update from plugin. extracting info from payload Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:10 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:10 musical-fidelity volumio[6537]: info: ------------------------------ 27ms Dec 23 05:58:10 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:10 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Clearing queue after UPNP request Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::ClearQueue Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::stop Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 05:58:21 musical-fidelity volumio[6537]: verbose: UNSET VOLATILE Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::stPlaybackTimer Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::updateTrackBlock Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrackBlock Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::serviceStop Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:21 musical-fidelity volumio[6537]: info: ControllerMpd::stop Dec 23 05:58:21 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CorePlayQueue::clearPlayQueue Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CorePlayQueue::saveQueue Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushQueue Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Dec 23 05:58:21 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Dec 23 05:58:21 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:21 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:21 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:21 musical-fidelity volumio[6537]: info: sendMpdCommand stop took 119 milliseconds Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Starting UPNP Playback Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Preparing playback through UPNP Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioGetState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 05:58:21 musical-fidelity volumio[6537]: error: updateQueue error: null Dec 23 05:58:21 musical-fidelity volumio[6537]: info: ------------------------------ 6ms Dec 23 05:58:21 musical-fidelity volumio[6537]: info: sendMpdCommand status took 5 milliseconds Dec 23 05:58:21 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CorePlayQueue::getTrack 0 Dec 23 05:58:21 musical-fidelity volumio[6537]: 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} Dec 23 05:58:21 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService stop Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:21 musical-fidelity volumio[6537]: info: No code Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:21 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:21 musical-fidelity volumio[6537]: info: ------------------------------ 31ms Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:21 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:22 musical-fidelity volumio[6537]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Julius%20Asal/SIENA%20TAPES/c08be27d-cbee-40b0-a25c-b16c17fe516d.jpg' Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Dec 23 05:58:22 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Dec 23 05:58:22 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Dec 23 05:58:22 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Dec 23 05:58:22 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Dec 23 05:58:22 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: ------------------------------ 2ms Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Dec 23 05:58:22 musical-fidelity volumio[6537]: ---------------------------- MPD announces system playlist update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: Ignoring MPD Status Update Dec 23 05:58:22 musical-fidelity volumio[6537]: info: ------------------------------ 3ms Dec 23 05:58:24 musical-fidelity volumio[6537]: info: Dec 23 05:58:24 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:24 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:24 musical-fidelity volumio[6537]: info: Dec 23 05:58:24 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:24 musical-fidelity volumio[6537]: info: sendMpdCommand status took 52 milliseconds Dec 23 05:58:24 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:24 musical-fidelity volumio[6537]: info: sendMpdCommand status took 3 milliseconds Dec 23 05:58:24 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:24 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Townies","artist":"Wednesday","album":"Bleeds","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=329468701&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473101&hmac=PtPFQnIY_sPp8vl2GxpZZFvHpco","trackType":"qobuz"} Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus stop Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:24 musical-fidelity volumio[6537]: info: ------------------------------ 75ms Dec 23 05:58:24 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:24 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1393 Kbps","isStreaming":false,"title":"Townies","artist":"Wednesday","album":"Bleeds","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=329468701&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473101&hmac=PtPFQnIY_sPp8vl2GxpZZFvHpco","trackType":"qobuz"} Dec 23 05:58:24 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:58:24 musical-fidelity volumio[6537]: info: Received an update from plugin. extracting info from payload Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:24 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:24 musical-fidelity volumio[6537]: info: ------------------------------ 60ms Dec 23 05:58:24 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:24 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:24 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:28 musical-fidelity volumio[6537]: info: Dec 23 05:58:28 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:28 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:28 musical-fidelity volumio[6537]: info: sendMpdCommand status took 4 milliseconds Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:28 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:28 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4249,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1558 Kbps","isStreaming":false,"title":"Townies","artist":"Wednesday","album":"Bleeds","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=329468701&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473101&hmac=PtPFQnIY_sPp8vl2GxpZZFvHpco","trackType":"qobuz"} Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService pause Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreStateMachine::stPlaybackTimer Dec 23 05:58:28 musical-fidelity volumio[6537]: info: ------------------------------ 23ms Dec 23 05:58:28 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:28 musical-fidelity volumio[6537]: info: Dec 23 05:58:28 musical-fidelity volumio[6537]: ---------------------------- MPD announces state update: player Dec 23 05:58:28 musical-fidelity volumio[6537]: info: ControllerMpd::getState Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand status Dec 23 05:58:28 musical-fidelity volumio[6537]: info: sendMpdCommand status took 1 milliseconds Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseState Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 05:58:28 musical-fidelity volumio[6537]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: ControllerMpd::parseTrackInfo Dec 23 05:58:28 musical-fidelity volumio[6537]: info: ControllerMpd::pushState Dec 23 05:58:28 musical-fidelity volumio[6537]: info: CoreCommandRouter::servicePushState Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: In UPNP mode Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4748,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1607 Kbps","isStreaming":false,"title":"Townies","artist":"Wednesday","album":"Bleeds","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=329468701&fmt=7&profile=raw&app_id=195822208&cid=3599692&etsp=1766473101&hmac=PtPFQnIY_sPp8vl2GxpZZFvHpco","trackType":"qobuz"} Dec 23 05:58:28 musical-fidelity volumio[6537]: verbose: CURRENT POSITION 0 Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState stateService play Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreStateMachine::syncState currentStatus play Dec 23 05:58:29 musical-fidelity volumio[6537]: info: Received an update from plugin. extracting info from payload Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreStateMachine::pushState Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreCommandRouter::volumioPushState Dec 23 05:58:29 musical-fidelity volumio[6537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 05:58:29 musical-fidelity volumio[6537]: info: ------------------------------ 25ms Dec 23 05:58:29 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:29 musical-fidelity volumio[6537]: info: Updating RAAT Signal Path Dec 23 05:58:36 musical-fidelity systemd[1]: Starting Cleanup of Temporary Directories... Dec 23 05:58:36 musical-fidelity volumio[6537]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 23 05:58:36 musical-fidelity systemd[1]: Started Cleanup of Temporary Directories. Dec 23 05:58:36 musical-fidelity volumio[6537]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 23 05:58:36 musical-fidelity volumio[6537]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Dec 23 05:58:41 musical-fidelity volumio[6537]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 05:58:41 musical-fidelity volumio[6537]: { Error: connect ETIMEDOUT 69.171.234.48:443 Dec 23 05:58:41 musical-fidelity volumio[6537]: at Object._errnoException (util.js:1022:11) Dec 23 05:58:41 musical-fidelity volumio[6537]: at _exceptionWithHostPort (util.js:1044:20) Dec 23 05:58:41 musical-fidelity volumio[6537]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 23 05:58:41 musical-fidelity volumio[6537]: code: 'ETIMEDOUT', Dec 23 05:58:41 musical-fidelity volumio[6537]: errno: 'ETIMEDOUT', Dec 23 05:58:41 musical-fidelity volumio[6537]: syscall: 'connect', Dec 23 05:58:41 musical-fidelity volumio[6537]: address: '69.171.234.48', Dec 23 05:58:41 musical-fidelity volumio[6537]: port: 443 } Dec 23 05:58:41 musical-fidelity volumio[6537]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 05:58:42 musical-fidelity sudo[8087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 05:57 Dec 23 05:58:42 musical-fidelity sudo[8087]: 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"