-- Logs begin at Mon 2024-11-18 20:29:49 CST, end at Sat 2025-07-05 20:49:26 CDT. -- Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:17 minidsp-shd volumio[1057]: info: sendMpdCommand status took 6 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: info: sendMpdCommand status took 3 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 2 Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 2 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService stop Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::play index undefined Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::startPlaybackTimer Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 05 20:48:17 minidsp-shd volumio[1057]: info: [1751766497074] ControllerQobuz::clearAddPlayTrack Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 63ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 60ms Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:17 minidsp-shd volumio[1057]: info: sendMpdCommand stop took 24 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: info: sendMpdCommand status took 3 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:17 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 21ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: sendMpdCommand clear took 23 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/155560710" Jul 05 20:48:17 minidsp-shd volumio[1057]: STREAMING PROXY: Handling url /?data=qobuz://song/155560710 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:17 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 23ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Executing endpoint getStreamUrlqobuz Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 05 20:48:17 minidsp-shd volumio[1057]: info: getStreamUrl took 344 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1264046&eid=155560710&fmt=6&profile=raw&range=20-30&app_id=539451548&etsp=1751770097&hmac=FNB9mDVUZMi1Gh9rL-6bm4FFuuE Jul 05 20:48:17 minidsp-shd volumio[1057]: STREAMING PROXY: Response: 200, length: 3183575 Jul 05 20:48:17 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:48:17 minidsp-shd volumio[1057]: STREAMING PROXY: Client dropped request, destroying Jul 05 20:48:17 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/155560710" Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 564ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 562ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/155560710" took 5 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 20:48:17 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:17 minidsp-shd volumio[1057]: STREAMING PROXY: Handling url /?data=qobuz://song/155560710 Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Jul 05 20:48:17 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 19ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: sendMpdCommand play took 17 milliseconds Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 14ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: ------------------------------ 9ms Jul 05 20:48:17 minidsp-shd volumio[1057]: info: Executing endpoint getStreamUrlqobuz Jul 05 20:48:17 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 05 20:48:18 minidsp-shd volumio[1057]: info: getStreamUrl took 281 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1264046&eid=155560710&fmt=6&profile=raw&range=20-30&app_id=539451548&etsp=1751770097&hmac=FNB9mDVUZMi1Gh9rL-6bm4FFuuE Jul 05 20:48:18 minidsp-shd volumio[1057]: STREAMING PROXY: Response: 200, length: 3183575 Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Jul 05 20:48:18 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Jul 05 20:48:18 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Jul 05 20:48:18 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Jul 05 20:48:18 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand status took 14 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand status took 11 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand status took 6 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Jul 05 20:48:18 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Jul 05 20:48:18 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand status took 35 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand status took 20 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560710","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560710","trackType":"qobuz"} Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560710","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560710","trackType":"qobuz"} Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560710","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560710","trackType":"qobuz"} Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ------------------------------ 199ms Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ------------------------------ 236ms Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand status took 217 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 202 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 199 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560710","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560710","trackType":"qobuz"} Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560710","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560710","trackType":"qobuz"} Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ------------------------------ 405ms Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ------------------------------ 391ms Jul 05 20:48:18 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 235 milliseconds Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"822 Kbps","isStreaming":false,"title":"155560710","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560710","trackType":"qobuz"} Jul 05 20:48:18 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 3 Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:18 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:18 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:18 minidsp-shd volumio[1057]: STREAMING PROXY: Client dropped request, destroying Jul 05 20:48:18 minidsp-shd volumio[1057]: info: ------------------------------ 544ms Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Jul 05 20:48:48 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Jul 05 20:48:48 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:48 minidsp-shd volumio[1057]: info: sendMpdCommand status took 6 milliseconds Jul 05 20:48:48 minidsp-shd volumio[1057]: info: sendMpdCommand status took 3 milliseconds Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 3 Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 3 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService stop Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::play index undefined Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::startPlaybackTimer Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: [1751766528585] ControllerQobuz::clearAddPlayTrack Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ------------------------------ 61ms Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ------------------------------ 57ms Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Jul 05 20:48:48 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:48 minidsp-shd volumio[1057]: info: sendMpdCommand stop took 24 milliseconds Jul 05 20:48:48 minidsp-shd volumio[1057]: info: sendMpdCommand status took 3 milliseconds Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:48 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ------------------------------ 24ms Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Jul 05 20:48:48 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:48 minidsp-shd volumio[1057]: info: sendMpdCommand clear took 26 milliseconds Jul 05 20:48:48 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/102297852" Jul 05 20:48:48 minidsp-shd volumio[1057]: STREAMING PROXY: Handling url /?data=qobuz://song/102297852 Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Jul 05 20:48:48 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Jul 05 20:48:48 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:48 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:48:48 minidsp-shd volumio[1057]: info: ------------------------------ 23ms Jul 05 20:48:48 minidsp-shd volumio[1057]: info: Executing endpoint getStreamUrlqobuz Jul 05 20:48:48 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 05 20:48:49 minidsp-shd volumio[1057]: info: getStreamUrl took 345 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1264046&eid=102297852&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1751770128&hmac=A7p_jg0aF48kWl666rzb4PurzNE Jul 05 20:48:49 minidsp-shd volumio[1057]: STREAMING PROXY: Response: 200, length: 6400580 Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/102297852" Jul 05 20:48:49 minidsp-shd volumio[1057]: STREAMING PROXY: Client dropped request, destroying Jul 05 20:48:49 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:48:49 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 549ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 547ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/102297852" took 6 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:49 minidsp-shd volumio[1057]: STREAMING PROXY: Handling url /?data=qobuz://song/102297852 Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand play took 13 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 12ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 11ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 7ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Executing endpoint getStreamUrlqobuz Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 05 20:48:49 minidsp-shd volumio[1057]: info: getStreamUrl took 322 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1264046&eid=102297852&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1751770129&hmac=PRGMwAkhQ8ThG5ZrFlQ85u_Y3jQ Jul 05 20:48:49 minidsp-shd volumio[1057]: STREAMING PROXY: Response: 200, length: 6400580 Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand status took 14 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand status took 10 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand status took 7 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Jul 05 20:48:49 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand status took 25 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand status took 12 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand status took 9 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"102297852","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/102297852","trackType":"qobuz"} Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 0 Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"102297852","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/102297852","trackType":"qobuz"} Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 0 Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"102297852","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/102297852","trackType":"qobuz"} Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 0 Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:49 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:49 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 151ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 172ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ------------------------------ 170ms Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 132 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 126 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 115 milliseconds Jul 05 20:48:49 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:48:49 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:49 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:50 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"102297852","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/102297852","trackType":"qobuz"} Jul 05 20:48:50 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 0 Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:50 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:50 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:50 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"102297852","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/102297852","trackType":"qobuz"} Jul 05 20:48:50 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 0 Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:50 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:50 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:48:50 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"102297852","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/102297852","trackType":"qobuz"} Jul 05 20:48:50 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 0 Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:48:50 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:48:50 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:48:50 minidsp-shd volumio[1057]: info: ------------------------------ 388ms Jul 05 20:48:50 minidsp-shd volumio[1057]: info: ------------------------------ 373ms Jul 05 20:48:50 minidsp-shd volumio[1057]: info: ------------------------------ 371ms Jul 05 20:48:50 minidsp-shd volumio[1057]: STREAMING PROXY: Client dropped request, destroying Jul 05 20:49:19 minidsp-shd volumio[1057]: info: Jul 05 20:49:19 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:19 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:19 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:19 minidsp-shd volumio[1057]: info: Jul 05 20:49:19 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:19 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:19 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:19 minidsp-shd volumio[1057]: info: sendMpdCommand status took 6 milliseconds Jul 05 20:49:19 minidsp-shd volumio[1057]: info: sendMpdCommand status took 7 milliseconds Jul 05 20:49:19 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:19 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:19 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 0 Jul 05 20:49:19 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 05 20:49:19 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 0 Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService stop Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreStateMachine::play index undefined Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:19 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:19 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:19 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:19 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:19 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:19 minidsp-shd volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreStateMachine::startPlaybackTimer Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:20 minidsp-shd volumio[1057]: info: [1751766560014] ControllerQobuz::clearAddPlayTrack Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 75ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 72ms Jul 05 20:49:20 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:20 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:20 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Jul 05 20:49:20 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:20 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:20 minidsp-shd volumio[1057]: info: sendMpdCommand stop took 24 milliseconds Jul 05 20:49:20 minidsp-shd volumio[1057]: info: sendMpdCommand status took 4 milliseconds Jul 05 20:49:20 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 20:49:20 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:20 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:20 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 34ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Jul 05 20:49:20 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: sendMpdCommand clear took 49 milliseconds Jul 05 20:49:20 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/155560719" Jul 05 20:49:20 minidsp-shd volumio[1057]: STREAMING PROXY: Handling url /?data=qobuz://song/155560719 Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Jul 05 20:49:20 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Jul 05 20:49:20 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:49:20 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 25ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Executing endpoint getStreamUrlqobuz Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 05 20:49:20 minidsp-shd volumio[1057]: info: getStreamUrl took 362 milliseconds Jul 05 20:49:20 minidsp-shd volumio[1057]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1264046&eid=155560719&fmt=6&profile=raw&range=20-30&app_id=539451548&etsp=1751770160&hmac=xF2K2zTTe3IwdzmdFgB9V_iiGOk Jul 05 20:49:20 minidsp-shd volumio[1057]: STREAMING PROXY: Response: 200, length: 2364139 Jul 05 20:49:20 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:49:20 minidsp-shd volumio[1057]: error: updateQueue error: null Jul 05 20:49:20 minidsp-shd volumio[1057]: STREAMING PROXY: Client dropped request, destroying Jul 05 20:49:20 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/155560719" Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 593ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 590ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Jul 05 20:49:20 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/155560719" took 5 milliseconds Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 20:49:20 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Jul 05 20:49:20 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:49:20 minidsp-shd volumio[1057]: STREAMING PROXY: Handling url /?data=qobuz://song/155560719 Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Jul 05 20:49:20 minidsp-shd volumio[1057]: ---------------------------- MPD announces system playlist update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Ignoring MPD Status Update Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 18ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: sendMpdCommand play took 15 milliseconds Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 14ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: ------------------------------ 8ms Jul 05 20:49:20 minidsp-shd volumio[1057]: info: Executing endpoint getStreamUrlqobuz Jul 05 20:49:20 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 05 20:49:21 minidsp-shd volumio[1057]: info: getStreamUrl took 315 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1264046&eid=155560719&fmt=6&profile=raw&range=20-30&app_id=539451548&etsp=1751770160&hmac=xF2K2zTTe3IwdzmdFgB9V_iiGOk Jul 05 20:49:21 minidsp-shd volumio[1057]: STREAMING PROXY: Response: 200, length: 2364139 Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Jul 05 20:49:21 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Jul 05 20:49:21 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Jul 05 20:49:21 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Jul 05 20:49:21 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand status took 13 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand status took 12 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand status took 9 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Jul 05 20:49:21 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Jul 05 20:49:21 minidsp-shd volumio[1057]: ---------------------------- MPD announces state update: player Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::getState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand status took 25 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand status took 11 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand status took 7 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseState Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560719","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560719","trackType":"qobuz"} Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560719","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560719","trackType":"qobuz"} Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560719","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560719","trackType":"qobuz"} Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ------------------------------ 124ms Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ------------------------------ 154ms Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ------------------------------ 153ms Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 117 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 113 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: info: sendMpdCommand playlistinfo took 114 milliseconds Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: ControllerMpd::parseTrackInfo Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"155560719","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560719","trackType":"qobuz"} Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"155560719","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560719","trackType":"qobuz"} Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ControllerMpd::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::servicePushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CorePlayQueue::getTrack 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"155560719","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155560719","trackType":"qobuz"} Jul 05 20:49:21 minidsp-shd volumio[1057]: verbose: CURRENT POSITION 1 Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState stateService play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::syncState currentStatus play Jul 05 20:49:21 minidsp-shd volumio[1057]: info: Received an update from plugin. extracting info from payload Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreStateMachine::pushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: CoreCommandRouter::volumioPushState Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output update for this device Jul 05 20:49:21 minidsp-shd volumio[1057]: info: MRS: Pushing multiroomSync output Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ------------------------------ 330ms Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ------------------------------ 314ms Jul 05 20:49:21 minidsp-shd volumio[1057]: info: ------------------------------ 331ms Jul 05 20:49:21 minidsp-shd volumio[1057]: STREAMING PROXY: Client dropped request, destroying Jul 05 20:49:25 minidsp-shd volumio[1057]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 20:49:25 minidsp-shd volumio[1057]: Error: read ETIMEDOUT Jul 05 20:49:25 minidsp-shd volumio[1057]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Jul 05 20:49:25 minidsp-shd volumio[1057]: errno: -110, Jul 05 20:49:25 minidsp-shd volumio[1057]: code: 'ETIMEDOUT', Jul 05 20:49:25 minidsp-shd volumio[1057]: syscall: 'read' Jul 05 20:49:25 minidsp-shd volumio[1057]: } Jul 05 20:49:25 minidsp-shd volumio[1057]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 20:49:26 minidsp-shd sudo[4353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 20:48 Jul 05 20:49:26 minidsp-shd sudo[4353]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"