-- Logs begin at Fri 2025-03-07 18:26:33 MSK, end at Sat 2025-03-08 19:42:52 MSK. -- Mar 08 19:41:03 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::ClearQueue Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::serviceStop Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::serviceStop Mar 08 19:41:03 rivo volumio[3128]: info: [1741452063227] ControllerQobuz::stop Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:03 rivo volumio[3128]: info: ControllerMpd::stop Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::addQueueItems Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::addQueueItems Mar 08 19:41:03 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:03 rivo volumio[3128]: info: Adding Item to queue: qobuz://album/hm7yw862v4lxb Mar 08 19:41:03 rivo volumio[3128]: info: Exploding uri qobuz://album/hm7yw862v4lxb in service qobuz Mar 08 19:41:03 rivo volumio[3128]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 08 19:41:03 rivo volumio[3128]: info: Mar 08 19:41:03 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:03 rivo volumio[3128]: info: sendMpdCommand stop took 63 milliseconds Mar 08 19:41:03 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:03 rivo volumio[3128]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:03 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:03 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:03 rivo volumio[3128]: 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":"67784048","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/67784048","trackType":"qobuz"} Mar 08 19:41:03 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:03 rivo volumio[3128]: info: No code Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:03 rivo volumio[3128]: info: ------------------------------ 33ms Mar 08 19:41:03 rivo volumio[3128]: info: Signalling Playback active due to playback status change Mar 08 19:41:03 rivo volumio[3128]: info: MCU Signalled Playback Inactive Mar 08 19:41:03 rivo volumio[3128]: info: MCU Signalled Playback Active Mar 08 19:41:03 rivo volumio[3128]: info: explodeUri took 722 milliseconds Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::volumioPlay Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::play index 0 Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:03 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:41:03 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:03 rivo volumio[3128]: info: [1741452063959] ControllerQobuz::clearAddPlayTrack Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:03 rivo volumio[3128]: info: sendMpdCommand stop took 4 milliseconds Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:41:03 rivo volumio[3128]: info: Mar 08 19:41:03 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:03 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:03 rivo volumio[3128]: info: sendMpdCommand clear took 2 milliseconds Mar 08 19:41:03 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/49337559" Mar 08 19:41:03 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:41:03 rivo volumio[3128]: info: ------------------------------ 2ms Mar 08 19:41:03 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/49337559 Mar 08 19:41:03 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:03 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:04 rivo volumio[3128]: info: getStreamUrl took 474 milliseconds Mar 08 19:41:04 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=49337559&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455664&hmac=xCGUv2WuICTCZ7hwXAOaeqq1ic0 Mar 08 19:41:04 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 23432535 Mar 08 19:41:04 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:04 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/49337559" Mar 08 19:41:04 rivo volumio[3128]: info: Mar 08 19:41:04 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:04 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:04 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/49337559" took 4 milliseconds Mar 08 19:41:04 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:04 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:41:04 rivo volumio[3128]: info: ------------------------------ 3ms Mar 08 19:41:04 rivo volumio[3128]: info: sendMpdCommand play took 1 milliseconds Mar 08 19:41:04 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/49337559 Mar 08 19:41:04 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:04 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:05 rivo volumio[3128]: info: getStreamUrl took 433 milliseconds Mar 08 19:41:05 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=49337559&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455665&hmac=zcWCwPB6UpHxUaYC2ePP8coWQZk Mar 08 19:41:05 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 23432535 Mar 08 19:41:05 rivo volumio[3128]: info: Mar 08 19:41:05 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:05 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:05 rivo volumio[3128]: info: Mar 08 19:41:05 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:05 rivo volumio[3128]: info: sendMpdCommand status took 9 milliseconds Mar 08 19:41:05 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:05 rivo volumio[3128]: info: sendMpdCommand status took 2 milliseconds Mar 08 19:41:05 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:05 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:05 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:05 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:05 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"49337559","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/49337559","trackType":"qobuz"} Mar 08 19:41:05 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:05 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:05 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:05 rivo volumio[3128]: info: ------------------------------ 16ms Mar 08 19:41:05 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 19:41:05 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:05 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:05 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:05 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:05 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"49337559","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/49337559","trackType":"qobuz"} Mar 08 19:41:05 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:05 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:05 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:41:05 rivo volumio[3128]: info: Received an update from plugin. extracting info from payload Mar 08 19:41:05 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:05 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:05 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:05 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:05 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:05 rivo volumio[3128]: info: ------------------------------ 22ms Mar 08 19:41:06 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:18 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:41:18 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:41:18 rivo volumio[3128]: info: Discovery: Getting this device information Mar 08 19:41:18 rivo volumio[3128]: info: CoreCommandRouter::volumioGetState Mar 08 19:41:18 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:41:21 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::ClearQueue Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:21 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:21 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::serviceStop Mar 08 19:41:21 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::serviceStop Mar 08 19:41:21 rivo volumio[3128]: info: [1741452081770] ControllerQobuz::stop Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:21 rivo volumio[3128]: info: ControllerMpd::stop Mar 08 19:41:21 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:21 rivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Mar 08 19:41:21 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::addQueueItems Mar 08 19:41:21 rivo volumio[3128]: info: CorePlayQueue::addQueueItems Mar 08 19:41:21 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:21 rivo volumio[3128]: info: Adding Item to queue: qobuz://album/dq714n01x3m5a Mar 08 19:41:21 rivo volumio[3128]: info: Exploding uri qobuz://album/dq714n01x3m5a in service qobuz Mar 08 19:41:21 rivo volumio[3128]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 08 19:41:21 rivo volumio[3128]: info: Mar 08 19:41:21 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:21 rivo volumio[3128]: info: sendMpdCommand stop took 46 milliseconds Mar 08 19:41:21 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:21 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:21 rivo volumio[3128]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:41:21 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:21 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:21 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:41:21 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:21 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:21 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:21 rivo volumio[3128]: 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":"49337559","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/49337559","trackType":"qobuz"} Mar 08 19:41:21 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:21 rivo volumio[3128]: info: No code Mar 08 19:41:21 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:21 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:21 rivo volumio[3128]: info: ------------------------------ 22ms Mar 08 19:41:21 rivo volumio[3128]: info: Signalling Playback active due to playback status change Mar 08 19:41:21 rivo volumio[3128]: info: MCU Signalled Playback Inactive Mar 08 19:41:21 rivo volumio[3128]: info: MCU Signalled Playback Active Mar 08 19:41:22 rivo volumio[3128]: info: explodeUri took 698 milliseconds Mar 08 19:41:22 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:22 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:22 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:22 rivo volumio[3128]: info: CoreCommandRouter::volumioPlay Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::play index 0 Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:22 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:22 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:41:22 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:22 rivo volumio[3128]: info: [1741452082480] ControllerQobuz::clearAddPlayTrack Mar 08 19:41:22 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:22 rivo volumio[3128]: info: sendMpdCommand stop took 6 milliseconds Mar 08 19:41:22 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:41:22 rivo volumio[3128]: info: Mar 08 19:41:22 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:22 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:22 rivo volumio[3128]: info: sendMpdCommand clear took 5 milliseconds Mar 08 19:41:22 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/92854418" Mar 08 19:41:22 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:41:22 rivo volumio[3128]: info: ------------------------------ 2ms Mar 08 19:41:22 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/92854418 Mar 08 19:41:22 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:22 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:23 rivo volumio[3128]: info: getStreamUrl took 502 milliseconds Mar 08 19:41:23 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=92854418&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455682&hmac=9F_xBIadnzeGKWFsZyiM9KM9e_g Mar 08 19:41:23 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 18327594 Mar 08 19:41:23 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:23 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/92854418" Mar 08 19:41:23 rivo volumio[3128]: info: Mar 08 19:41:23 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:23 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:23 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/92854418" took 4 milliseconds Mar 08 19:41:23 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:23 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:41:23 rivo volumio[3128]: info: ------------------------------ 3ms Mar 08 19:41:23 rivo volumio[3128]: info: sendMpdCommand play took 2 milliseconds Mar 08 19:41:23 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/92854418 Mar 08 19:41:23 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:23 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:24 rivo volumio[3128]: info: getStreamUrl took 493 milliseconds Mar 08 19:41:24 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=92854418&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455683&hmac=POzEWiZ0KvFYuUjI8Rlw_PA5xjM Mar 08 19:41:24 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 18327594 Mar 08 19:41:24 rivo volumio[3128]: info: Mar 08 19:41:24 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:24 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:24 rivo volumio[3128]: info: Mar 08 19:41:24 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:24 rivo volumio[3128]: info: sendMpdCommand status took 37 milliseconds Mar 08 19:41:24 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:24 rivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Mar 08 19:41:24 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:24 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:24 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:24 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:24 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"92854418","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92854418","trackType":"qobuz"} Mar 08 19:41:24 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:24 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:24 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:24 rivo volumio[3128]: info: ------------------------------ 59ms Mar 08 19:41:24 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 08 19:41:24 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:24 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:24 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:24 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:24 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"940 Kbps","isStreaming":false,"title":"92854418","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92854418","trackType":"qobuz"} Mar 08 19:41:24 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:24 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:24 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:41:24 rivo volumio[3128]: info: Received an update from plugin. extracting info from payload Mar 08 19:41:24 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:24 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:24 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:24 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:24 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:24 rivo volumio[3128]: info: ------------------------------ 48ms Mar 08 19:41:24 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:29 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::ClearQueue Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:29 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:29 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::serviceStop Mar 08 19:41:29 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::serviceStop Mar 08 19:41:29 rivo volumio[3128]: info: [1741452089446] ControllerQobuz::stop Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:29 rivo volumio[3128]: info: ControllerMpd::stop Mar 08 19:41:29 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:29 rivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Mar 08 19:41:29 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::addQueueItems Mar 08 19:41:29 rivo volumio[3128]: info: CorePlayQueue::addQueueItems Mar 08 19:41:29 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:29 rivo volumio[3128]: info: Adding Item to queue: qobuz://album/0088807230509 Mar 08 19:41:29 rivo volumio[3128]: info: Exploding uri qobuz://album/0088807230509 in service qobuz Mar 08 19:41:29 rivo volumio[3128]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 08 19:41:29 rivo volumio[3128]: info: Mar 08 19:41:29 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:29 rivo volumio[3128]: info: sendMpdCommand stop took 52 milliseconds Mar 08 19:41:29 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:29 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:29 rivo volumio[3128]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:41:29 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:29 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:29 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:41:29 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:29 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:29 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:29 rivo volumio[3128]: 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":"92854418","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92854418","trackType":"qobuz"} Mar 08 19:41:29 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:29 rivo volumio[3128]: info: No code Mar 08 19:41:29 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:29 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:29 rivo volumio[3128]: info: ------------------------------ 20ms Mar 08 19:41:29 rivo volumio[3128]: info: Signalling Playback active due to playback status change Mar 08 19:41:29 rivo volumio[3128]: info: MCU Signalled Playback Inactive Mar 08 19:41:29 rivo volumio[3128]: info: MCU Signalled Playback Active Mar 08 19:41:30 rivo volumio[3128]: info: explodeUri took 559 milliseconds Mar 08 19:41:30 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:30 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:30 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:30 rivo volumio[3128]: info: CoreCommandRouter::volumioPlay Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::play index 0 Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:30 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:41:30 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:30 rivo volumio[3128]: info: [1741452090018] ControllerQobuz::clearAddPlayTrack Mar 08 19:41:30 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:30 rivo volumio[3128]: info: sendMpdCommand stop took 4 milliseconds Mar 08 19:41:30 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:41:30 rivo volumio[3128]: info: Mar 08 19:41:30 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:30 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:30 rivo volumio[3128]: info: sendMpdCommand clear took 1 milliseconds Mar 08 19:41:30 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/800792" Mar 08 19:41:30 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:41:30 rivo volumio[3128]: info: ------------------------------ 3ms Mar 08 19:41:30 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/800792 Mar 08 19:41:30 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:30 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:30 rivo volumio[3128]: info: getStreamUrl took 521 milliseconds Mar 08 19:41:30 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=800792&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455690&hmac=FKEpCzpkhWNDG7GuP-xsHUZwoiU Mar 08 19:41:30 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 39353828 Mar 08 19:41:30 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:30 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/800792" Mar 08 19:41:30 rivo volumio[3128]: info: Mar 08 19:41:30 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:30 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:30 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/800792" took 2 milliseconds Mar 08 19:41:30 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:30 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:41:30 rivo volumio[3128]: info: ------------------------------ 5ms Mar 08 19:41:30 rivo volumio[3128]: info: sendMpdCommand play took 5 milliseconds Mar 08 19:41:30 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/800792 Mar 08 19:41:30 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:30 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:31 rivo volumio[3128]: info: getStreamUrl took 437 milliseconds Mar 08 19:41:31 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=800792&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455691&hmac=KGa-Mv91rKI-kG_AxnqCVPohTQo Mar 08 19:41:31 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 39353828 Mar 08 19:41:31 rivo volumio[3128]: info: Mar 08 19:41:31 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:31 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:31 rivo volumio[3128]: info: Mar 08 19:41:31 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:31 rivo volumio[3128]: info: sendMpdCommand status took 9 milliseconds Mar 08 19:41:31 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:31 rivo volumio[3128]: info: sendMpdCommand status took 2 milliseconds Mar 08 19:41:31 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:31 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:31 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:31 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:31 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":368,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"800792","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/800792","trackType":"qobuz"} Mar 08 19:41:31 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:31 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:31 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:31 rivo volumio[3128]: info: ------------------------------ 15ms Mar 08 19:41:31 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 19:41:31 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:31 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:31 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:31 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:31 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":368,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"800792","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/800792","trackType":"qobuz"} Mar 08 19:41:31 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:31 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:31 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:41:31 rivo volumio[3128]: info: Received an update from plugin. extracting info from payload Mar 08 19:41:31 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:31 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:31 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:31 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:31 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:31 rivo volumio[3128]: info: ------------------------------ 24ms Mar 08 19:41:32 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:33 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::ClearQueue Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:33 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:33 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::serviceStop Mar 08 19:41:33 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::serviceStop Mar 08 19:41:33 rivo volumio[3128]: info: [1741452093737] ControllerQobuz::stop Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:33 rivo volumio[3128]: info: ControllerMpd::stop Mar 08 19:41:33 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:33 rivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Mar 08 19:41:33 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::addQueueItems Mar 08 19:41:33 rivo volumio[3128]: info: CorePlayQueue::addQueueItems Mar 08 19:41:33 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:33 rivo volumio[3128]: info: Adding Item to queue: qobuz://album/ap7pioo6tpxpb Mar 08 19:41:33 rivo volumio[3128]: info: Exploding uri qobuz://album/ap7pioo6tpxpb in service qobuz Mar 08 19:41:33 rivo volumio[3128]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 08 19:41:33 rivo volumio[3128]: info: Mar 08 19:41:33 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:33 rivo volumio[3128]: info: sendMpdCommand stop took 47 milliseconds Mar 08 19:41:33 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:33 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:33 rivo volumio[3128]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:41:33 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:33 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:33 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:41:33 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:33 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:33 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:33 rivo volumio[3128]: 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":"800792","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/800792","trackType":"qobuz"} Mar 08 19:41:33 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:33 rivo volumio[3128]: info: No code Mar 08 19:41:33 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:33 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:33 rivo volumio[3128]: info: ------------------------------ 20ms Mar 08 19:41:33 rivo volumio[3128]: info: Signalling Playback active due to playback status change Mar 08 19:41:33 rivo volumio[3128]: info: MCU Signalled Playback Inactive Mar 08 19:41:33 rivo volumio[3128]: info: MCU Signalled Playback Active Mar 08 19:41:34 rivo volumio[3128]: info: explodeUri took 1186 milliseconds Mar 08 19:41:34 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:34 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:34 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:34 rivo volumio[3128]: info: CoreCommandRouter::volumioPlay Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::play index 0 Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:34 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:34 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:41:34 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:34 rivo volumio[3128]: info: [1741452094943] ControllerQobuz::clearAddPlayTrack Mar 08 19:41:34 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:34 rivo volumio[3128]: info: sendMpdCommand stop took 5 milliseconds Mar 08 19:41:34 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:41:34 rivo volumio[3128]: info: Mar 08 19:41:34 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:34 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:34 rivo volumio[3128]: info: sendMpdCommand clear took 2 milliseconds Mar 08 19:41:34 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/92850100" Mar 08 19:41:34 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:41:34 rivo volumio[3128]: info: ------------------------------ 2ms Mar 08 19:41:34 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/92850100 Mar 08 19:41:34 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:34 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:35 rivo volumio[3128]: info: getStreamUrl took 580 milliseconds Mar 08 19:41:35 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=92850100&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455695&hmac=ACblWIg9HBShAJn_ad9PUe916yE Mar 08 19:41:35 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 10852927 Mar 08 19:41:35 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:35 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/92850100" Mar 08 19:41:35 rivo volumio[3128]: info: Mar 08 19:41:35 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:35 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:35 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/92850100" took 1 milliseconds Mar 08 19:41:35 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:35 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:41:35 rivo volumio[3128]: info: ------------------------------ 3ms Mar 08 19:41:35 rivo volumio[3128]: info: sendMpdCommand play took 2 milliseconds Mar 08 19:41:35 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/92850100 Mar 08 19:41:35 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:35 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:36 rivo volumio[3128]: info: getStreamUrl took 488 milliseconds Mar 08 19:41:36 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=92850100&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455696&hmac=jQk5nT0HuG2t8JuSC7g9pQSqAH8 Mar 08 19:41:36 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 10852927 Mar 08 19:41:36 rivo volumio[3128]: info: Mar 08 19:41:36 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:36 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:36 rivo volumio[3128]: info: Mar 08 19:41:36 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:36 rivo volumio[3128]: info: sendMpdCommand status took 69 milliseconds Mar 08 19:41:36 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:36 rivo volumio[3128]: info: sendMpdCommand status took 2 milliseconds Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:36 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:36 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:36 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:36 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:36 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":171,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"533 Kbps","isStreaming":false,"title":"92850100","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92850100","trackType":"qobuz"} Mar 08 19:41:36 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:36 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:36 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:36 rivo volumio[3128]: info: ------------------------------ 82ms Mar 08 19:41:36 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 08 19:41:36 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:36 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:36 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:36 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:36 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":171,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"565 Kbps","isStreaming":false,"title":"92850100","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92850100","trackType":"qobuz"} Mar 08 19:41:36 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:36 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:36 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:41:36 rivo volumio[3128]: info: Received an update from plugin. extracting info from payload Mar 08 19:41:36 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:36 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:36 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:36 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:36 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:36 rivo volumio[3128]: info: ------------------------------ 79ms Mar 08 19:41:37 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::volumioNext Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::next Mar 08 19:41:46 rivo volumio[3128]: info: [1741452106843] ControllerQobuz::next Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::next Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::serviceStop Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::serviceStop Mar 08 19:41:46 rivo volumio[3128]: info: [1741452106850] ControllerQobuz::stop Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:46 rivo volumio[3128]: info: ControllerMpd::stop Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:46 rivo volumio[3128]: info: Mar 08 19:41:46 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:46 rivo volumio[3128]: info: sendMpdCommand stop took 22 milliseconds Mar 08 19:41:46 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:46 rivo volumio[3128]: info: [1741452106875] ControllerQobuz::clearAddPlayTrack Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:46 rivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Mar 08 19:41:46 rivo volumio[3128]: info: sendMpdCommand stop took 2 milliseconds Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:41:46 rivo volumio[3128]: info: Mar 08 19:41:46 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:46 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:46 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 19:41:46 rivo volumio[3128]: info: sendMpdCommand clear took 5 milliseconds Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:46 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/92850101" Mar 08 19:41:46 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:46 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:46 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:46 rivo volumio[3128]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 08 19:41:46 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/92850101 Mar 08 19:41:46 rivo volumio[3128]: info: ------------------------------ 20ms Mar 08 19:41:46 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:41:46 rivo volumio[3128]: info: ------------------------------ 11ms Mar 08 19:41:46 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:46 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:46 rivo volumio[3128]: info: MCU Signalled Playback Inactive Mar 08 19:41:47 rivo volumio[3128]: info: getStreamUrl took 447 milliseconds Mar 08 19:41:47 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=92850101&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455707&hmac=Vh34ONzWwcvhnxm0RnA7MmKOrrs Mar 08 19:41:48 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 9755199 Mar 08 19:41:48 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/92850101" Mar 08 19:41:48 rivo volumio[3128]: info: Mar 08 19:41:48 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:48 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:48 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/92850101" took 2 milliseconds Mar 08 19:41:48 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:41:48 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/92850101 Mar 08 19:41:48 rivo volumio[3128]: info: ------------------------------ 5ms Mar 08 19:41:48 rivo volumio[3128]: info: sendMpdCommand play took 4 milliseconds Mar 08 19:41:48 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:41:48 rivo volumio[3128]: info: Discovery: Getting this device information Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::volumioGetState Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:41:48 rivo volumio[3128]: info: getStreamUrl took 443 milliseconds Mar 08 19:41:48 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=92850101&fmt=6&profile=raw&app_id=539451548&cid=2588227&etsp=1741455708&hmac=pKPGbHMEYVvWOSCIvKPm6vVhJqg Mar 08 19:41:48 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 9755199 Mar 08 19:41:48 rivo volumio[3128]: info: Mar 08 19:41:48 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:48 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:48 rivo volumio[3128]: info: sendMpdCommand status took 13 milliseconds Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:48 rivo volumio[3128]: info: Mar 08 19:41:48 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:48 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:48 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:48 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:48 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:48 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":168,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"92850101","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92850101","trackType":"qobuz"} Mar 08 19:41:48 rivo volumio[3128]: verbose: CURRENT POSITION 1 Mar 08 19:41:48 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:48 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:48 rivo volumio[3128]: info: ------------------------------ 24ms Mar 08 19:41:48 rivo volumio[3128]: info: sendMpdCommand status took 12 milliseconds Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:48 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:41:48 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:48 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:48 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:48 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":168,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"92850101","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92850101","trackType":"qobuz"} Mar 08 19:41:48 rivo volumio[3128]: verbose: CURRENT POSITION 1 Mar 08 19:41:48 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:48 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:41:48 rivo volumio[3128]: info: Received an update from plugin. extracting info from payload Mar 08 19:41:48 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:48 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:48 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:48 rivo volumio[3128]: info: ------------------------------ 32ms Mar 08 19:41:48 rivo volumio[3128]: info: Signalling Playback active due to playback status change Mar 08 19:41:48 rivo volumio[3128]: info: MCU Signalled Playback Active Mar 08 19:41:49 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:55 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::ClearQueue Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:55 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:55 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::serviceStop Mar 08 19:41:55 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::serviceStop Mar 08 19:41:55 rivo volumio[3128]: info: [1741452115358] ControllerQobuz::stop Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:55 rivo volumio[3128]: info: ControllerMpd::stop Mar 08 19:41:55 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:55 rivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Mar 08 19:41:55 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::addQueueItems Mar 08 19:41:55 rivo volumio[3128]: info: CorePlayQueue::addQueueItems Mar 08 19:41:55 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:41:55 rivo volumio[3128]: info: Adding Item to queue: qobuz://album/0886443612303 Mar 08 19:41:55 rivo volumio[3128]: info: Exploding uri qobuz://album/0886443612303 in service qobuz Mar 08 19:41:55 rivo volumio[3128]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 08 19:41:55 rivo volumio[3128]: info: sendMpdCommand stop took 53 milliseconds Mar 08 19:41:55 rivo volumio[3128]: info: Mar 08 19:41:55 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:55 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:55 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:55 rivo volumio[3128]: info: sendMpdCommand status took 2 milliseconds Mar 08 19:41:55 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:55 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:55 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:41:55 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:55 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:55 rivo volumio[3128]: info: CorePlayQueue::getTrack 1 Mar 08 19:41:55 rivo volumio[3128]: 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":"92850101","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/92850101","trackType":"qobuz"} Mar 08 19:41:55 rivo volumio[3128]: verbose: CURRENT POSITION 1 Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:55 rivo volumio[3128]: info: No code Mar 08 19:41:55 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:55 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:55 rivo volumio[3128]: info: ------------------------------ 18ms Mar 08 19:41:55 rivo volumio[3128]: info: Signalling Playback active due to playback status change Mar 08 19:41:55 rivo volumio[3128]: info: MCU Signalled Playback Inactive Mar 08 19:41:55 rivo volumio[3128]: info: MCU Signalled Playback Active Mar 08 19:41:56 rivo volumio[3128]: info: explodeUri took 709 milliseconds Mar 08 19:41:56 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:41:56 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:41:56 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:41:56 rivo volumio[3128]: info: CoreCommandRouter::volumioPlay Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::play index 0 Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:41:56 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:56 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:41:56 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:56 rivo volumio[3128]: info: [1741452116091] ControllerQobuz::clearAddPlayTrack Mar 08 19:41:56 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:41:56 rivo volumio[3128]: info: sendMpdCommand stop took 6 milliseconds Mar 08 19:41:56 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:41:56 rivo volumio[3128]: info: Mar 08 19:41:56 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:56 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:56 rivo volumio[3128]: info: sendMpdCommand clear took 2 milliseconds Mar 08 19:41:56 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/32437830" Mar 08 19:41:56 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:41:56 rivo volumio[3128]: info: ------------------------------ 2ms Mar 08 19:41:56 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/32437830 Mar 08 19:41:56 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:56 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:56 rivo volumio[3128]: info: getStreamUrl took 656 milliseconds Mar 08 19:41:56 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=32437830&fmt=27&profile=raw&app_id=539451548&cid=2588227&etsp=1741455716&hmac=jmg31WF8zgBLIh9hjeOe81S8prA Mar 08 19:41:57 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 347035600 Mar 08 19:41:57 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/32437830" Mar 08 19:41:57 rivo volumio[3128]: info: Mar 08 19:41:57 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:41:57 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:41:57 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/32437830" took 2 milliseconds Mar 08 19:41:57 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:41:57 rivo volumio[3128]: info: ------------------------------ 2ms Mar 08 19:41:57 rivo volumio[3128]: info: sendMpdCommand play took 2 milliseconds Mar 08 19:41:57 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/32437830 Mar 08 19:41:57 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:41:57 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:41:57 rivo volumio[3128]: info: getStreamUrl took 448 milliseconds Mar 08 19:41:57 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=32437830&fmt=27&profile=raw&app_id=539451548&cid=2588227&etsp=1741455717&hmac=7fv9I-TT24GfXWt7-LsL3vOCOTw Mar 08 19:41:57 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 347035600 Mar 08 19:41:57 rivo volumio[3128]: info: Mar 08 19:41:57 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:57 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:57 rivo volumio[3128]: info: Mar 08 19:41:57 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:41:57 rivo volumio[3128]: info: sendMpdCommand status took 96 milliseconds Mar 08 19:41:57 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:57 rivo volumio[3128]: info: sendMpdCommand status took 5 milliseconds Mar 08 19:41:57 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:57 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:57 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:57 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:57 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":591,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32437830","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/32437830","trackType":"qobuz"} Mar 08 19:41:57 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:57 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:57 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:41:57 rivo volumio[3128]: info: ------------------------------ 111ms Mar 08 19:41:57 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 19:41:57 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:41:57 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:41:57 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:41:57 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:41:57 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":745,"duration":591,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4556 Kbps","isStreaming":false,"title":"32437830","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/32437830","trackType":"qobuz"} Mar 08 19:41:57 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:41:57 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:41:57 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:41:57 rivo volumio[3128]: info: Received an update from plugin. extracting info from payload Mar 08 19:41:57 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:57 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:41:57 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:57 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:41:57 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:41:57 rivo volumio[3128]: info: ------------------------------ 37ms Mar 08 19:42:11 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::ClearQueue Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::stPlaybackTimer Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::serviceStop Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::serviceStop Mar 08 19:42:11 rivo volumio[3128]: info: [1741452131338] ControllerQobuz::stop Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:42:11 rivo volumio[3128]: info: ControllerMpd::stop Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::addQueueItems Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::addQueueItems Mar 08 19:42:11 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:11 rivo volumio[3128]: info: Adding Item to queue: qobuz://album/0060254709212 Mar 08 19:42:11 rivo volumio[3128]: info: Exploding uri qobuz://album/0060254709212 in service qobuz Mar 08 19:42:11 rivo volumio[3128]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 08 19:42:11 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:42:11 rivo volumio[3128]: info: Mar 08 19:42:11 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:42:11 rivo volumio[3128]: info: sendMpdCommand stop took 48 milliseconds Mar 08 19:42:11 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:42:11 rivo volumio[3128]: info: sendMpdCommand status took 1 milliseconds Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:42:11 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:42:11 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:11 rivo volumio[3128]: 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":"32437830","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/32437830","trackType":"qobuz"} Mar 08 19:42:11 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::syncState stateService stop Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:42:11 rivo volumio[3128]: info: No code Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:42:11 rivo volumio[3128]: info: ------------------------------ 20ms Mar 08 19:42:11 rivo volumio[3128]: info: Signalling Playback active due to playback status change Mar 08 19:42:11 rivo volumio[3128]: info: MCU Signalled Playback Inactive Mar 08 19:42:11 rivo volumio[3128]: info: MCU Signalled Playback Active Mar 08 19:42:11 rivo volumio[3128]: info: explodeUri took 631 milliseconds Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:42:11 rivo volumio[3128]: info: CoreCommandRouter::volumioPlay Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::play index 0 Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:11 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:42:11 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:11 rivo volumio[3128]: info: [1741452131989] ControllerQobuz::clearAddPlayTrack Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:42:11 rivo volumio[3128]: info: sendMpdCommand stop took 5 milliseconds Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:42:11 rivo volumio[3128]: info: Mar 08 19:42:11 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:42:11 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:42:11 rivo volumio[3128]: info: sendMpdCommand clear took 1 milliseconds Mar 08 19:42:11 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/30047426" Mar 08 19:42:11 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:42:11 rivo volumio[3128]: info: ------------------------------ 2ms Mar 08 19:42:12 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/30047426 Mar 08 19:42:12 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:42:12 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:42:12 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:12 rivo volumio[3128]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::ClearQueue Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:12 rivo volumio[3128]: info: CorePlayQueue::clearPlayQueue Mar 08 19:42:12 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:42:12 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::addQueueItems Mar 08 19:42:12 rivo volumio[3128]: info: CorePlayQueue::addQueueItems Mar 08 19:42:12 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:12 rivo volumio[3128]: info: Adding Item to queue: qobuz://album/0060254709212 Mar 08 19:42:12 rivo volumio[3128]: info: Using cached record of: qobuz://album/0060254709212 Mar 08 19:42:12 rivo volumio[3128]: info: CoreCommandRouter::volumioPushQueue Mar 08 19:42:12 rivo volumio[3128]: info: CorePlayQueue::saveQueue Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::updateTrackBlock Mar 08 19:42:12 rivo volumio[3128]: info: CorePlayQueue::getTrackBlock Mar 08 19:42:12 rivo volumio[3128]: info: CoreCommandRouter::volumioPlay Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::play index 0 Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::stop Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::play index undefined Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:42:12 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:42:12 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:12 rivo volumio[3128]: info: [1741452132057] ControllerQobuz::clearAddPlayTrack Mar 08 19:42:12 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 19:42:12 rivo volumio[3128]: info: getStreamUrl took 495 milliseconds Mar 08 19:42:12 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=30047426&fmt=7&profile=raw&app_id=539451548&cid=2588227&etsp=1741455732&hmac=5BgwQvckia2aA-HB6wgMc24P2Uo Mar 08 19:42:12 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 51011740 Mar 08 19:42:12 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:42:12 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/30047426" Mar 08 19:42:12 rivo volumio[3128]: info: sendMpdCommand stop took 739 milliseconds Mar 08 19:42:12 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 19:42:12 rivo volumio[3128]: info: Mar 08 19:42:12 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:42:12 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:42:12 rivo volumio[3128]: info: Mar 08 19:42:12 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:42:12 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:42:12 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/30047426" took 6 milliseconds Mar 08 19:42:12 rivo volumio[3128]: info: sendMpdCommand clear took 5 milliseconds Mar 08 19:42:12 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:42:12 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:42:12 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/30047426" Mar 08 19:42:12 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/30047426 Mar 08 19:42:12 rivo volumio[3128]: error: updateQueue error: null Mar 08 19:42:12 rivo volumio[3128]: info: ------------------------------ 9ms Mar 08 19:42:12 rivo volumio[3128]: info: sendMpdCommand play took 7 milliseconds Mar 08 19:42:12 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:42:12 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:42:13 rivo volumio[3128]: info: getStreamUrl took 442 milliseconds Mar 08 19:42:13 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=30047426&fmt=7&profile=raw&app_id=539451548&cid=2588227&etsp=1741455733&hmac=LMa79gsaf6_Odyj7nkkjsgLB6f4 Mar 08 19:42:13 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 51011740 Mar 08 19:42:13 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:42:13 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/30047426" Mar 08 19:42:13 rivo volumio[3128]: info: Mar 08 19:42:13 rivo volumio[3128]: ---------------------------- MPD announces system playlist update Mar 08 19:42:13 rivo volumio[3128]: info: Ignoring MPD Status Update Mar 08 19:42:13 rivo volumio[3128]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/30047426" took 2 milliseconds Mar 08 19:42:13 rivo volumio[3128]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 19:42:13 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand play Mar 08 19:42:13 rivo volumio[3128]: info: ------------------------------ 2ms Mar 08 19:42:13 rivo volumio[3128]: info: sendMpdCommand play took 2 milliseconds Mar 08 19:42:13 rivo volumio[3128]: STREAMING PROXY: Handling url /?data=qobuz://song/30047426 Mar 08 19:42:13 rivo volumio[3128]: info: Executing endpoint getStreamUrlqobuz Mar 08 19:42:13 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 08 19:42:13 rivo volumio[3128]: info: getStreamUrl took 473 milliseconds Mar 08 19:42:13 rivo volumio[3128]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3315167&eid=30047426&fmt=7&profile=raw&app_id=539451548&cid=2588227&etsp=1741455733&hmac=LMa79gsaf6_Odyj7nkkjsgLB6f4 Mar 08 19:42:13 rivo volumio[3128]: STREAMING PROXY: Response: 200, length: 51011740 Mar 08 19:42:13 rivo volumio[3128]: info: Mar 08 19:42:13 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:42:13 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:42:13 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:42:14 rivo volumio[3128]: info: Mar 08 19:42:14 rivo volumio[3128]: ---------------------------- MPD announces state update: player Mar 08 19:42:14 rivo volumio[3128]: info: sendMpdCommand status took 90 milliseconds Mar 08 19:42:14 rivo volumio[3128]: info: ControllerMpd::getState Mar 08 19:42:14 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand status Mar 08 19:42:14 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:42:14 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:42:14 rivo volumio[3128]: info: sendMpdCommand status took 4 milliseconds Mar 08 19:42:14 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 19:42:14 rivo volumio[3128]: verbose: ControllerMpd::parseState Mar 08 19:42:14 rivo volumio[3128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 19:42:14 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:42:14 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:42:14 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:42:14 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:14 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":397,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"30047426","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/30047426","trackType":"qobuz"} Mar 08 19:42:14 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:42:14 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:42:14 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus stop Mar 08 19:42:14 rivo volumio[3128]: info: ------------------------------ 100ms Mar 08 19:42:14 rivo volumio[3128]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 19:42:14 rivo volumio[3128]: verbose: ControllerMpd::parseTrackInfo Mar 08 19:42:14 rivo volumio[3128]: info: ControllerMpd::pushState Mar 08 19:42:14 rivo volumio[3128]: info: CoreCommandRouter::servicePushState Mar 08 19:42:14 rivo volumio[3128]: info: CorePlayQueue::getTrack 0 Mar 08 19:42:14 rivo volumio[3128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":808,"duration":397,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"30047426","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/30047426","trackType":"qobuz"} Mar 08 19:42:14 rivo volumio[3128]: verbose: CURRENT POSITION 0 Mar 08 19:42:14 rivo volumio[3128]: info: CoreStateMachine::syncState stateService play Mar 08 19:42:14 rivo volumio[3128]: info: CoreStateMachine::syncState currentStatus play Mar 08 19:42:14 rivo volumio[3128]: info: Received an update from plugin. extracting info from payload Mar 08 19:42:14 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:42:14 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:42:14 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:42:14 rivo volumio[3128]: info: CoreStateMachine::pushState Mar 08 19:42:14 rivo volumio[3128]: info: CoreCommandRouter::volumioPushState Mar 08 19:42:14 rivo volumio[3128]: info: ------------------------------ 36ms Mar 08 19:42:16 rivo volumio[3128]: STREAMING PROXY: Client dropped request, destroying Mar 08 19:42:18 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:42:18 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:42:18 rivo volumio[3128]: info: Discovery: Getting this device information Mar 08 19:42:18 rivo volumio[3128]: info: CoreCommandRouter::volumioGetState Mar 08 19:42:18 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:42:37 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 08 19:42:37 rivo volumio[3128]: info: CURURI: music-library Mar 08 19:42:37 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:39 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 08 19:42:39 rivo volumio[3128]: info: CURURI: music-library/USB Mar 08 19:42:39 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:41 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 08 19:42:41 rivo volumio[3128]: info: CURURI: music-library/USB/Music Mar 08 19:42:41 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:47 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 08 19:42:47 rivo volumio[3128]: info: CURURI: music-library/USB/Music/Классика Mar 08 19:42:47 rivo volumio[3128]: info: Preload queue cleared Mar 08 19:42:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:42:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:42:48 rivo volumio[3128]: info: Discovery: Getting this device information Mar 08 19:42:48 rivo volumio[3128]: info: CoreCommandRouter::volumioGetState Mar 08 19:42:48 rivo volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:42:51 rivo volumio[3128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 19:42:51 rivo volumio[3128]: Error: connect ETIMEDOUT 151.101.38.79:80 Mar 08 19:42:51 rivo volumio[3128]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 08 19:42:51 rivo volumio[3128]: errno: -110, Mar 08 19:42:51 rivo volumio[3128]: code: 'ETIMEDOUT', Mar 08 19:42:51 rivo volumio[3128]: syscall: 'connect', Mar 08 19:42:51 rivo volumio[3128]: address: '151.101.38.79', Mar 08 19:42:51 rivo volumio[3128]: port: 80 Mar 08 19:42:51 rivo volumio[3128]: } Mar 08 19:42:51 rivo volumio[3128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 19:42:52 rivo sudo[28862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 19:41 Mar 08 19:42:52 rivo sudo[28862]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"