-- Logs begin at Wed 2026-02-11 17:22:28 UTC, end at Thu 2026-02-12 13:46:23 UTC. -- Feb 12 13:45:00 musical-fidelity volumio[18853]: Searching plugin music_service/qobuz Feb 12 13:45:00 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 12 13:45:01 musical-fidelity volumio[18853]: info: searchQOBUZUri took 1607 milliseconds Feb 12 13:45:01 musical-fidelity volumio[18853]: info: All search sources collected, pushing search results Feb 12 13:45:01 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 12 13:45:02 musical-fidelity volumio[18853]: info: searchQOBUZUri took 1847 milliseconds Feb 12 13:45:02 musical-fidelity volumio[18853]: info: All search sources collected, pushing search results Feb 12 13:45:03 musical-fidelity volumio[18853]: info: handleBrowseUri took 1782 milliseconds Feb 12 13:45:03 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:03 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:04 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:04 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:04 musical-fidelity dhcpcd[1095]: eth0: adding route to 192.168.0.0/24 Feb 12 13:45:04 musical-fidelity dhcpcd[1095]: eth0: adding default route via 192.168.0.1 Feb 12 13:45:04 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:04 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:04 musical-fidelity dhcpcd[1095]: eth0: removing route to 192.168.0.0/24 Feb 12 13:45:04 musical-fidelity dhcpcd[1095]: eth0: removing default route via 192.168.0.1 Feb 12 13:45:06 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 12 13:45:07 musical-fidelity volumio[18853]: info: handleBrowseUri took 1114 milliseconds Feb 12 13:45:07 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:07 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPause Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreStateMachine::pause Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreStateMachine::stPlaybackTimer Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreStateMachine::servicePause Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePause Feb 12 13:45:10 musical-fidelity volumio[18853]: info: ControllerMpd::pause Feb 12 13:45:10 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand pause Feb 12 13:45:10 musical-fidelity volumio[18853]: info: sendMpdCommand pause took 81 milliseconds Feb 12 13:45:10 musical-fidelity volumio[18853]: info: Feb 12 13:45:10 musical-fidelity volumio[18853]: ---------------------------- MPD announces state update: player Feb 12 13:45:10 musical-fidelity volumio[18853]: info: ControllerMpd::getState Feb 12 13:45:10 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand status Feb 12 13:45:10 musical-fidelity volumio[18853]: info: sendMpdCommand status took 4 milliseconds Feb 12 13:45:10 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseState Feb 12 13:45:10 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:45:10 musical-fidelity volumio[18853]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 13:45:10 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseTrackInfo Feb 12 13:45:10 musical-fidelity volumio[18853]: info: ControllerMpd::pushState Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePushState Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:10 musical-fidelity volumio[18853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":39493,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2169 Kbps","isStreaming":false,"title":"Can't We Be Friends?","artist":"Ella Fitzgerald","album":"Ella And Louis","uri":"USB/QOBUZ/Ella Fitzgerald & Louis Armstrong/Ella And Louis/Ella Fitzgerald & Louis Armstrong-01 Can't We Be Friends_.flac","trackType":"flac"} Feb 12 13:45:10 musical-fidelity volumio[18853]: verbose: CURRENT POSITION 0 Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState stateService pause Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState currentStatus pause Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:10 musical-fidelity volumio[18853]: info: CoreStateMachine::stPlaybackTimer Feb 12 13:45:10 musical-fidelity volumio[18853]: info: ------------------------------ 16ms Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::ClearQueue Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::stop Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:45:12 musical-fidelity volumio[18853]: verbose: UNSET VOLATILE Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::updateTrackBlock Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrackBlock Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::stPlaybackTimer Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::serviceStop Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::serviceStop Feb 12 13:45:12 musical-fidelity volumio[18853]: info: ControllerMpd::stop Feb 12 13:45:12 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::clearPlayQueue Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::saveQueue Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushQueue Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::addQueueItems Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::addQueueItems Feb 12 13:45:12 musical-fidelity volumio[18853]: info: Adding Item to queue: qobuz://album/maw7ul1wqbpoa Feb 12 13:45:12 musical-fidelity volumio[18853]: info: Exploding uri qobuz://album/maw7ul1wqbpoa in service qobuz Feb 12 13:45:12 musical-fidelity volumio[18853]: info: Feb 12 13:45:12 musical-fidelity volumio[18853]: ---------------------------- MPD announces state update: player Feb 12 13:45:12 musical-fidelity volumio[18853]: info: sendMpdCommand stop took 34 milliseconds Feb 12 13:45:12 musical-fidelity volumio[18853]: info: ControllerMpd::getState Feb 12 13:45:12 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand status Feb 12 13:45:12 musical-fidelity volumio[18853]: info: sendMpdCommand status took 2 milliseconds Feb 12 13:45:12 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseState Feb 12 13:45:12 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:45:12 musical-fidelity volumio[18853]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 13:45:12 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseTrackInfo Feb 12 13:45:12 musical-fidelity volumio[18853]: info: ControllerMpd::pushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:12 musical-fidelity volumio[18853]: 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":"Can't We Be Friends?","artist":"Ella Fitzgerald","album":"Ella And Louis","uri":"USB/QOBUZ/Ella Fitzgerald & Louis Armstrong/Ella And Louis/Ella Fitzgerald & Louis Armstrong-01 Can't We Be Friends_.flac","trackType":"flac"} Feb 12 13:45:12 musical-fidelity volumio[18853]: verbose: CURRENT POSITION 0 Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState stateService stop Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState currentStatus stop Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:12 musical-fidelity volumio[18853]: info: No code Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:12 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:12 musical-fidelity volumio[18853]: info: ------------------------------ 38ms Feb 12 13:45:13 musical-fidelity volumio[18853]: info: explodeUri took 472 milliseconds Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CorePlayQueue::saveQueue Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushQueue Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::updateTrackBlock Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrackBlock Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPlay Feb 12 13:45:13 musical-fidelity volumio[18853]: verbose: UNSET VOLATILE Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::play index 0 Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::stop Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:45:13 musical-fidelity volumio[18853]: verbose: UNSET VOLATILE Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::play index undefined Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CoreStateMachine::startPlaybackTimer Feb 12 13:45:13 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:13 musical-fidelity volumio[18853]: info: [1770903913342] ControllerQobuz::clearAddPlayTrack Feb 12 13:45:13 musical-fidelity volumio[18853]: info: getStreamUrl took 511 milliseconds Feb 12 13:45:13 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 13:45:13 musical-fidelity volumio[18853]: info: sendMpdCommand stop took 3 milliseconds Feb 12 13:45:13 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 13:45:13 musical-fidelity volumio[18853]: info: Feb 12 13:45:13 musical-fidelity volumio[18853]: ---------------------------- MPD announces system playlist update Feb 12 13:45:13 musical-fidelity volumio[18853]: info: Ignoring MPD Status Update Feb 12 13:45:13 musical-fidelity volumio[18853]: info: sendMpdCommand clear took 2 milliseconds Feb 12 13:45:13 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210210&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907513&hmac=SqOTKqhOb8UGawyVEQsmcppyV2s" Feb 12 13:45:13 musical-fidelity volumio[18853]: error: updateQueue error: null Feb 12 13:45:13 musical-fidelity volumio[18853]: info: ------------------------------ 2ms Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210210&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907513&hmac=SqOTKqhOb8UGawyVEQsmcppyV2s" Feb 12 13:45:14 musical-fidelity volumio[18853]: info: Feb 12 13:45:14 musical-fidelity volumio[18853]: ---------------------------- MPD announces system playlist update Feb 12 13:45:14 musical-fidelity volumio[18853]: info: Ignoring MPD Status Update Feb 12 13:45:14 musical-fidelity volumio[18853]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210210&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907513&hmac=SqOTKqhOb8UGawyVEQsmcppyV2s" took 2 milliseconds Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand play Feb 12 13:45:14 musical-fidelity volumio[18853]: info: ------------------------------ 6ms Feb 12 13:45:14 musical-fidelity volumio[18853]: info: sendMpdCommand play took 4 milliseconds Feb 12 13:45:14 musical-fidelity volumio[18853]: info: Feb 12 13:45:14 musical-fidelity volumio[18853]: ---------------------------- MPD announces state update: player Feb 12 13:45:14 musical-fidelity volumio[18853]: info: ControllerMpd::getState Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand status Feb 12 13:45:14 musical-fidelity volumio[18853]: info: Feb 12 13:45:14 musical-fidelity volumio[18853]: ---------------------------- MPD announces state update: player Feb 12 13:45:14 musical-fidelity volumio[18853]: info: sendMpdCommand status took 7 milliseconds Feb 12 13:45:14 musical-fidelity volumio[18853]: info: ControllerMpd::getState Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand status Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseState Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:45:14 musical-fidelity volumio[18853]: info: sendMpdCommand status took 6 milliseconds Feb 12 13:45:14 musical-fidelity volumio[18853]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseState Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseTrackInfo Feb 12 13:45:14 musical-fidelity volumio[18853]: info: ControllerMpd::pushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=3142217&eid=52210210&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907513&hmac=SqOTKqhOb8UGawyVEQsmcppyV2s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210210&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907513&hmac=SqOTKqhOb8UGawyVEQsmcppyV2s","trackType":"qobuz"} Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: CURRENT POSITION 0 Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState stateService play Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState currentStatus stop Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:14 musical-fidelity volumio[18853]: info: ------------------------------ 42ms Feb 12 13:45:14 musical-fidelity volumio[18853]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseTrackInfo Feb 12 13:45:14 musical-fidelity volumio[18853]: info: ControllerMpd::pushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378,"duration":226,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2041 Kbps","isStreaming":false,"title":"file?uid=3142217&eid=52210210&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907513&hmac=SqOTKqhOb8UGawyVEQsmcppyV2s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210210&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907513&hmac=SqOTKqhOb8UGawyVEQsmcppyV2s","trackType":"qobuz"} Feb 12 13:45:14 musical-fidelity volumio[18853]: verbose: CURRENT POSITION 0 Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState stateService play Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState currentStatus play Feb 12 13:45:14 musical-fidelity volumio[18853]: info: Received an update from plugin. extracting info from payload Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:14 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:14 musical-fidelity volumio[18853]: info: ------------------------------ 108ms Feb 12 13:45:16 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 12 13:45:16 musical-fidelity volumio[18853]: info: handleBrowseUri took 516 milliseconds Feb 12 13:45:17 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:17 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 12 13:45:20 musical-fidelity volumio[18853]: info: handleBrowseUri took 479 milliseconds Feb 12 13:45:20 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: Executing endpoint metavolumio Feb 12 13:45:20 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 12 13:45:25 musical-fidelity volumio[18853]: info: Adding qobuz://album/maw7ul1wqbpoa to favourites with specific qobuz method Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioNext Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::next Feb 12 13:45:46 musical-fidelity volumio[18853]: info: [1770903946314] ControllerQobuz::next Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::next Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::stop Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:45:46 musical-fidelity volumio[18853]: verbose: UNSET VOLATILE Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::stPlaybackTimer Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::updateTrackBlock Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrackBlock Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::serviceStop Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 0 Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreCommandRouter::serviceStop Feb 12 13:45:46 musical-fidelity volumio[18853]: info: [1770903946322] ControllerQobuz::stop Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 13:45:46 musical-fidelity volumio[18853]: info: ControllerMpd::stop Feb 12 13:45:46 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 13:45:46 musical-fidelity volumio[18853]: info: sendMpdCommand stop took 247 milliseconds Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::play index undefined Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 1 Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::startPlaybackTimer Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 1 Feb 12 13:45:46 musical-fidelity volumio[18853]: info: [1770903946572] ControllerQobuz::clearAddPlayTrack Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::updateTrackBlock Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrackBlock Feb 12 13:45:46 musical-fidelity volumio[18853]: info: Feb 12 13:45:46 musical-fidelity volumio[18853]: ---------------------------- MPD announces state update: player Feb 12 13:45:46 musical-fidelity volumio[18853]: info: ControllerMpd::getState Feb 12 13:45:46 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand status Feb 12 13:45:46 musical-fidelity volumio[18853]: info: sendMpdCommand status took 6 milliseconds Feb 12 13:45:46 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseState Feb 12 13:45:46 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:45:46 musical-fidelity volumio[18853]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 13:45:46 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseTrackInfo Feb 12 13:45:46 musical-fidelity volumio[18853]: info: ControllerMpd::pushState Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePushState Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 1 Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:46 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 1 Feb 12 13:45:46 musical-fidelity volumio[18853]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Feb 12 13:45:46 musical-fidelity volumio[18853]: info: ------------------------------ 30ms Feb 12 13:45:47 musical-fidelity volumio[18853]: info: getStreamUrl took 479 milliseconds Feb 12 13:45:47 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 13:45:47 musical-fidelity volumio[18853]: info: sendMpdCommand stop took 2 milliseconds Feb 12 13:45:47 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 13:45:47 musical-fidelity volumio[18853]: info: Feb 12 13:45:47 musical-fidelity volumio[18853]: ---------------------------- MPD announces system playlist update Feb 12 13:45:47 musical-fidelity volumio[18853]: info: Ignoring MPD Status Update Feb 12 13:45:47 musical-fidelity volumio[18853]: info: sendMpdCommand clear took 3 milliseconds Feb 12 13:45:47 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210211&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907546&hmac=iRCcQ4-JFr8oTAKZyZpZJ3PG2HY" Feb 12 13:45:47 musical-fidelity volumio[18853]: error: updateQueue error: null Feb 12 13:45:47 musical-fidelity volumio[18853]: info: ------------------------------ 3ms Feb 12 13:45:47 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 12 13:45:47 musical-fidelity volumio[18853]: info: CURURI: music-library Feb 12 13:45:47 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210211&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907546&hmac=iRCcQ4-JFr8oTAKZyZpZJ3PG2HY" Feb 12 13:45:47 musical-fidelity volumio[18853]: info: Feb 12 13:45:47 musical-fidelity volumio[18853]: ---------------------------- MPD announces system playlist update Feb 12 13:45:47 musical-fidelity volumio[18853]: info: Ignoring MPD Status Update Feb 12 13:45:47 musical-fidelity volumio[18853]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210211&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907546&hmac=iRCcQ4-JFr8oTAKZyZpZJ3PG2HY" took 2 milliseconds Feb 12 13:45:47 musical-fidelity volumio[18853]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 13:45:47 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand play Feb 12 13:45:47 musical-fidelity volumio[18853]: info: ------------------------------ 19ms Feb 12 13:45:47 musical-fidelity volumio[18853]: info: sendMpdCommand play took 12 milliseconds Feb 12 13:45:48 musical-fidelity volumio[18853]: info: Feb 12 13:45:48 musical-fidelity volumio[18853]: ---------------------------- MPD announces state update: player Feb 12 13:45:48 musical-fidelity volumio[18853]: info: ControllerMpd::getState Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand status Feb 12 13:45:48 musical-fidelity volumio[18853]: info: Feb 12 13:45:48 musical-fidelity volumio[18853]: ---------------------------- MPD announces state update: player Feb 12 13:45:48 musical-fidelity volumio[18853]: info: sendMpdCommand status took 4 milliseconds Feb 12 13:45:48 musical-fidelity volumio[18853]: info: ControllerMpd::getState Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand status Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseState Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:45:48 musical-fidelity volumio[18853]: info: sendMpdCommand status took 4 milliseconds Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseState Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:45:48 musical-fidelity volumio[18853]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 12 13:45:48 musical-fidelity volumio[18853]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseTrackInfo Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: ControllerMpd::parseTrackInfo Feb 12 13:45:48 musical-fidelity volumio[18853]: info: ControllerMpd::pushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 1 Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":375,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=3142217&eid=52210211&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907546&hmac=iRCcQ4-JFr8oTAKZyZpZJ3PG2HY","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210211&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907546&hmac=iRCcQ4-JFr8oTAKZyZpZJ3PG2HY","trackType":"qobuz"} Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: CURRENT POSITION 1 Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState stateService play Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState currentStatus stop Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:48 musical-fidelity volumio[18853]: info: ControllerMpd::pushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::servicePushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CorePlayQueue::getTrack 1 Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":375,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2180 Kbps","isStreaming":false,"title":"file?uid=3142217&eid=52210211&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907546&hmac=iRCcQ4-JFr8oTAKZyZpZJ3PG2HY","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3142217&eid=52210211&fmt=7&profile=raw&app_id=539451548&cid=3730449&etsp=1770907546&hmac=iRCcQ4-JFr8oTAKZyZpZJ3PG2HY","trackType":"qobuz"} Feb 12 13:45:48 musical-fidelity volumio[18853]: verbose: CURRENT POSITION 1 Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState stateService play Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreStateMachine::syncState currentStatus play Feb 12 13:45:48 musical-fidelity volumio[18853]: info: Received an update from plugin. extracting info from payload Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreStateMachine::pushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumioPushState Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:45:48 musical-fidelity volumio[18853]: info: ------------------------------ 54ms Feb 12 13:45:48 musical-fidelity volumio[18853]: info: ------------------------------ 53ms Feb 12 13:45:48 musical-fidelity volumio[18853]: info: Updating push state because input changed Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::volumiosetSourceActivempd Feb 12 13:45:48 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:45:49 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 12 13:45:49 musical-fidelity volumio[18853]: info: CURURI: genres:// Feb 12 13:45:57 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 12 13:45:57 musical-fidelity volumio[18853]: info: CURURI: genres://Soul%2FFunk%2FR%26B Feb 12 13:46:02 musical-fidelity volumio[18853]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 12 13:46:02 musical-fidelity volumio[18853]: info: CURURI: genres://Vocal%20Jazz Feb 12 13:46:12 musical-fidelity volumio[18853]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ella%20Fitzgerald%20%26%20Louis%20Armstrong/b0d3f253-383a-4bf6-bc5e-744d0d6dc8df.jpg' Feb 12 13:46:13 musical-fidelity volumio[18853]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Malia/640f1e8a-815a-4117-b092-f43b23ccb698.jpg' Feb 12 13:46:23 musical-fidelity volumio[18853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 13:46:23 musical-fidelity volumio[18853]: { Error: connect ETIMEDOUT 23.73.2.84:443 Feb 12 13:46:23 musical-fidelity volumio[18853]: at Object._errnoException (util.js:1022:11) Feb 12 13:46:23 musical-fidelity volumio[18853]: at _exceptionWithHostPort (util.js:1044:20) Feb 12 13:46:23 musical-fidelity volumio[18853]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 12 13:46:23 musical-fidelity volumio[18853]: code: 'ETIMEDOUT', Feb 12 13:46:23 musical-fidelity volumio[18853]: errno: 'ETIMEDOUT', Feb 12 13:46:23 musical-fidelity volumio[18853]: syscall: 'connect', Feb 12 13:46:23 musical-fidelity volumio[18853]: address: '23.73.2.84', Feb 12 13:46:23 musical-fidelity volumio[18853]: port: 443 } Feb 12 13:46:23 musical-fidelity volumio[18853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 13:46:23 musical-fidelity sudo[25886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-12 13:45 Feb 12 13:46:23 musical-fidelity sudo[25886]: 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"