-- Logs begin at Wed 2025-04-16 00:13:29 CEST, end at Wed 2025-05-14 20:29:37 CEST. -- May 14 20:28:01 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 20:28:01 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 20:28:01 minidsp-shd volumio[643]: info: Discovery: Getting this device information May 14 20:28:01 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioGetState May 14 20:28:01 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 20:28:17 minidsp-shd volumio[643]: info: Preload queue cleared May 14 20:28:17 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioReplaceandPlayItems May 14 20:28:17 minidsp-shd volumio[643]: info: CoreStateMachine::ClearQueue May 14 20:28:17 minidsp-shd volumio[643]: info: CoreStateMachine::stop May 14 20:28:17 minidsp-shd volumio[643]: info: CoreStateMachine::serviceStop May 14 20:28:17 minidsp-shd volumio[643]: info: CoreCommandRouter::serviceStop May 14 20:28:17 minidsp-shd volumio[643]: info: [1747247297865] inputs::stop May 14 20:28:17 minidsp-shd volumio[643]: info: CorePlayQueue::clearPlayQueue May 14 20:28:17 minidsp-shd volumio[643]: info: CorePlayQueue::saveQueue May 14 20:28:17 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushQueue May 14 20:28:17 minidsp-shd volumio[643]: info: CoreStateMachine::addQueueItems May 14 20:28:17 minidsp-shd volumio[643]: info: CorePlayQueue::addQueueItems May 14 20:28:17 minidsp-shd volumio[643]: info: Preload queue cleared May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/296416020 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/296416020 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/134143295 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/134143295 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/134143236 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/134143236 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/333749840 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/333749840 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/329620445 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/329620445 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/264057997 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/264057997 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/94725716 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/94725716 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/93997009 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/93997009 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/92860330 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/92860330 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/334066453 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/334066453 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/255435228 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/255435228 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/122248939 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/122248939 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/291363187 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/291363187 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/291363180 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/291363180 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/291363189 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/291363189 in service qobuz May 14 20:28:17 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/291363185 May 14 20:28:17 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/291363185 in service qobuz May 14 20:28:18 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/291363183 May 14 20:28:18 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/291363183 in service qobuz May 14 20:28:18 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/291363182 May 14 20:28:18 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/291363182 in service qobuz May 14 20:28:18 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/291363181 May 14 20:28:18 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/291363181 in service qobuz May 14 20:28:18 minidsp-shd volumio[643]: info: Adding Item to queue: qobuz://song/264058001 May 14 20:28:18 minidsp-shd volumio[643]: info: Exploding uri qobuz://song/264058001 in service qobuz May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 813 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 824 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 800 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 802 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 873 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 774 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 817 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 939 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 835 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 854 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 888 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 963 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 981 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 968 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 937 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 1007 milliseconds May 14 20:28:18 minidsp-shd volumio[643]: info: explodeUri took 1035 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: info: explodeUri took 1047 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: info: explodeUri took 1096 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: info: explodeUri took 1135 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushQueue May 14 20:28:19 minidsp-shd volumio[643]: info: CorePlayQueue::saveQueue May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::updateTrackBlock May 14 20:28:19 minidsp-shd volumio[643]: info: CorePlayQueue::getTrackBlock May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPlay May 14 20:28:19 minidsp-shd volumio[643]: verbose: UNSET VOLATILE: Service: inputs May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::play index 4 May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::stop May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::stPlaybackTimer May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::updateTrackBlock May 14 20:28:19 minidsp-shd volumio[643]: info: CorePlayQueue::getTrackBlock May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:19 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 0 May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::serviceStop May 14 20:28:19 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 0 May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::serviceStop May 14 20:28:19 minidsp-shd volumio[643]: info: [1747247299185] ControllerQobuz::stop May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 20:28:19 minidsp-shd volumio[643]: info: ControllerMpd::stop May 14 20:28:19 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand stop May 14 20:28:19 minidsp-shd volumio[643]: info: sendMpdCommand stop took 19 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::play index undefined May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 20:28:19 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:19 minidsp-shd volumio[643]: info: CoreStateMachine::startPlaybackTimer May 14 20:28:19 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:19 minidsp-shd volumio[643]: info: [1747247299217] ControllerQobuz::clearAddPlayTrack May 14 20:28:19 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand stop May 14 20:28:19 minidsp-shd volumio[643]: info: sendMpdCommand stop took 29 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand clear May 14 20:28:19 minidsp-shd volumio[643]: info: May 14 20:28:19 minidsp-shd volumio[643]: ---------------------------- MPD announces system playlist update May 14 20:28:19 minidsp-shd volumio[643]: info: Ignoring MPD Status Update May 14 20:28:19 minidsp-shd volumio[643]: info: sendMpdCommand clear took 8 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/329620445" May 14 20:28:19 minidsp-shd volumio[643]: info: May 14 20:28:19 minidsp-shd volumio[643]: ---------------------------- MPD announces system playlist update May 14 20:28:19 minidsp-shd volumio[643]: info: Ignoring MPD Status Update May 14 20:28:19 minidsp-shd volumio[643]: info: May 14 20:28:19 minidsp-shd volumio[643]: ---------------------------- MPD announces system playlist update May 14 20:28:19 minidsp-shd volumio[643]: info: Ignoring MPD Status Update May 14 20:28:19 minidsp-shd volumio[643]: STREAMING PROXY: Handling url /?data=qobuz://song/329620445 May 14 20:28:19 minidsp-shd volumio[643]: error: updateQueue error: null May 14 20:28:19 minidsp-shd volumio[643]: info: ------------------------------ 46ms May 14 20:28:19 minidsp-shd volumio[643]: error: Upnp client error: Error: This socket has been ended by the other party May 14 20:28:19 minidsp-shd volumio[643]: info: Executing endpoint getStreamUrlqobuz May 14 20:28:19 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 14 20:28:19 minidsp-shd volumio[643]: info: getStreamUrl took 388 milliseconds May 14 20:28:19 minidsp-shd volumio[643]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3657601&eid=329620445&fmt=6&profile=raw&app_id=539451548&cid=2609748&etsp=1747250899&hmac=38GEC4pGAHtKPfcRB1tmq6DCoy0 May 14 20:28:20 minidsp-shd volumio[643]: STREAMING PROXY: Response: 200, length: 16873862 May 14 20:28:20 minidsp-shd volumio[643]: STREAMING PROXY: Client dropped request, destroying May 14 20:28:20 minidsp-shd volumio[643]: error: updateQueue error: null May 14 20:28:20 minidsp-shd volumio[643]: error: updateQueue error: null May 14 20:28:20 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/329620445" May 14 20:28:20 minidsp-shd volumio[643]: info: ------------------------------ 1207ms May 14 20:28:20 minidsp-shd volumio[643]: info: ------------------------------ 1204ms May 14 20:28:20 minidsp-shd volumio[643]: info: May 14 20:28:20 minidsp-shd volumio[643]: ---------------------------- MPD announces system playlist update May 14 20:28:20 minidsp-shd volumio[643]: info: Ignoring MPD Status Update May 14 20:28:20 minidsp-shd volumio[643]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/329620445" took 9 milliseconds May 14 20:28:20 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 20:28:20 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand play May 14 20:28:20 minidsp-shd volumio[643]: info: May 14 20:28:20 minidsp-shd volumio[643]: ---------------------------- MPD announces system playlist update May 14 20:28:20 minidsp-shd volumio[643]: info: Ignoring MPD Status Update May 14 20:28:20 minidsp-shd volumio[643]: info: May 14 20:28:20 minidsp-shd volumio[643]: ---------------------------- MPD announces system playlist update May 14 20:28:20 minidsp-shd volumio[643]: info: Ignoring MPD Status Update May 14 20:28:20 minidsp-shd volumio[643]: info: ------------------------------ 27ms May 14 20:28:20 minidsp-shd volumio[643]: info: sendMpdCommand play took 21 milliseconds May 14 20:28:20 minidsp-shd volumio[643]: info: ------------------------------ 20ms May 14 20:28:20 minidsp-shd volumio[643]: info: ------------------------------ 16ms May 14 20:28:20 minidsp-shd volumio[643]: STREAMING PROXY: Handling url /?data=qobuz://song/329620445 May 14 20:28:20 minidsp-shd volumio[643]: info: Executing endpoint getStreamUrlqobuz May 14 20:28:20 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 14 20:28:20 minidsp-shd volumio[643]: info: getStreamUrl took 368 milliseconds May 14 20:28:20 minidsp-shd volumio[643]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3657601&eid=329620445&fmt=6&profile=raw&app_id=539451548&cid=2609748&etsp=1747250900&hmac=A3Z12RTUjTaExUV0B85K1L4iCpA May 14 20:28:21 minidsp-shd volumio[643]: STREAMING PROXY: Response: 200, length: 16873862 May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 37 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 30 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 26 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 102 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 64 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 53 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 45 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 35 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 27 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus stop May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"414 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus play May 14 20:28:21 minidsp-shd volumio[643]: info: Received an update from plugin. extracting info from payload May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"532 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus play May 14 20:28:21 minidsp-shd volumio[643]: info: Received an update from plugin. extracting info from payload May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 272ms May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 315ms May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 307ms May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 166 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 152 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 152 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"532 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus play May 14 20:28:21 minidsp-shd volumio[643]: info: Received an update from plugin. extracting info from payload May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":434,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"484 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus play May 14 20:28:21 minidsp-shd volumio[643]: info: Received an update from plugin. extracting info from payload May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":434,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"484 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus play May 14 20:28:21 minidsp-shd volumio[643]: info: Received an update from plugin. extracting info from payload May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 475ms May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 392ms May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 387ms May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::stPlaybackTimer May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::servicePause May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePause May 14 20:28:21 minidsp-shd volumio[643]: info: [1747247301771] ControllerQobuz::pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pause May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand pause May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand pause took 7 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: May 14 20:28:21 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 20 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 13 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand status took 8 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 14 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 13 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 11 milliseconds May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":983,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"414 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::stPlaybackTimer May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":983,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"414 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::stPlaybackTimer May 14 20:28:21 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:21 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:21 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":983,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"414 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:21 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus pause May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:21 minidsp-shd volumio[643]: info: CoreStateMachine::stPlaybackTimer May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 162ms May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 156ms May 14 20:28:21 minidsp-shd volumio[643]: info: ------------------------------ 151ms May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPlay May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::play index undefined May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 20:28:22 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::startPlaybackTimer May 14 20:28:22 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:22 minidsp-shd volumio[643]: info: [1747247302518] ControllerQobuz::resume May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 20:28:22 minidsp-shd volumio[643]: info: ControllerMpd::resume May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand play May 14 20:28:22 minidsp-shd volumio[643]: info: May 14 20:28:22 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:22 minidsp-shd volumio[643]: info: sendMpdCommand play took 12 milliseconds May 14 20:28:22 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:22 minidsp-shd volumio[643]: info: May 14 20:28:22 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:22 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:22 minidsp-shd volumio[643]: info: May 14 20:28:22 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:28:22 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:28:22 minidsp-shd volumio[643]: info: sendMpdCommand status took 22 milliseconds May 14 20:28:22 minidsp-shd volumio[643]: info: sendMpdCommand status took 14 milliseconds May 14 20:28:22 minidsp-shd volumio[643]: info: sendMpdCommand status took 8 milliseconds May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:28:22 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 13 milliseconds May 14 20:28:22 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 11 milliseconds May 14 20:28:22 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 10 milliseconds May 14 20:28:22 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:28:22 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:22 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:22 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"458 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:22 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus pause May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:22 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:22 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:22 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"458 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:22 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus play May 14 20:28:22 minidsp-shd volumio[643]: info: Received an update from plugin. extracting info from payload May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:22 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:28:22 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:28:22 minidsp-shd volumio[643]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"458 Kbps","isStreaming":false,"title":"329620445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/329620445","trackType":"qobuz"} May 14 20:28:22 minidsp-shd volumio[643]: verbose: CURRENT POSITION 4 May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::syncState stateService play May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::syncState currentStatus play May 14 20:28:22 minidsp-shd volumio[643]: info: Received an update from plugin. extracting info from payload May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:22 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:22 minidsp-shd volumio[643]: info: ------------------------------ 217ms May 14 20:28:22 minidsp-shd volumio[643]: info: ------------------------------ 211ms May 14 20:28:22 minidsp-shd volumio[643]: info: ------------------------------ 206ms May 14 20:28:23 minidsp-shd volumio[643]: STREAMING PROXY: Client dropped request, destroying May 14 20:28:25 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume May 14 20:28:25 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:25 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:25 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:25 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:25 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:25 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:25 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume May 14 20:28:26 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume May 14 20:28:26 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:26 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:26 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:26 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:26 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:26 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:26 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume May 14 20:28:27 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume May 14 20:28:27 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:27 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:27 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:27 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:27 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:27 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume May 14 20:28:28 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume May 14 20:28:28 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:28 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:28:28 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:28 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:28:28 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:28:28 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume May 14 20:28:31 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 20:28:31 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 20:28:31 minidsp-shd volumio[643]: info: Discovery: Getting this device information May 14 20:28:31 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioGetState May 14 20:28:31 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 20:29:01 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 20:29:01 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 20:29:01 minidsp-shd volumio[643]: info: Discovery: Getting this device information May 14 20:29:01 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioGetState May 14 20:29:01 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 20:29:28 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri May 14 20:29:28 minidsp-shd volumio[643]: info: Preload queue cleared May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri May 14 20:29:30 minidsp-shd volumio[643]: info: Preload queue cleared May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::volumiosetSourceActiveno-source May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 20:29:30 minidsp-shd volumio[643]: Cannot find translation for source Presets May 14 20:29:30 minidsp-shd volumio[643]: Cannot find translation for source QOBUZ May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioStop May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::stop May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::stPlaybackTimer May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::updateTrackBlock May 14 20:29:30 minidsp-shd volumio[643]: info: CorePlayQueue::getTrackBlock May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:29:30 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::serviceStop May 14 20:29:30 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::serviceStop May 14 20:29:30 minidsp-shd volumio[643]: info: [1747247370851] ControllerQobuz::stop May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 20:29:30 minidsp-shd volumio[643]: info: ControllerMpd::stop May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand stop May 14 20:29:30 minidsp-shd volumio[643]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"} May 14 20:29:30 minidsp-shd volumio[643]: info: May 14 20:29:30 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:29:30 minidsp-shd volumio[643]: info: sendMpdCommand stop took 37 milliseconds May 14 20:29:30 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 20:29:30 minidsp-shd volumio[643]: info: May 14 20:29:30 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:29:30 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:29:30 minidsp-shd volumio[643]: info: May 14 20:29:30 minidsp-shd volumio[643]: ---------------------------- MPD announces state update: player May 14 20:29:30 minidsp-shd volumio[643]: info: ControllerMpd::getState May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand status May 14 20:29:30 minidsp-shd volumio[643]: info: sendMpdCommand status took 19 milliseconds May 14 20:29:30 minidsp-shd volumio[643]: info: sendMpdCommand status took 14 milliseconds May 14 20:29:30 minidsp-shd volumio[643]: info: sendMpdCommand status took 10 milliseconds May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::parseState May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 20:29:30 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 9 milliseconds May 14 20:29:30 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 9 milliseconds May 14 20:29:30 minidsp-shd volumio[643]: info: sendMpdCommand playlistinfo took 8 milliseconds May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:29:30 minidsp-shd volumio[643]: verbose: ControllerMpd::parseTrackInfo May 14 20:29:30 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:29:30 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:29:30 minidsp-shd volumio[643]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd May 14 20:29:30 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:29:30 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:29:30 minidsp-shd volumio[643]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd May 14 20:29:30 minidsp-shd volumio[643]: info: ControllerMpd::pushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:29:30 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:29:30 minidsp-shd volumio[643]: info: CorePlayQueue::getTrack 4 May 14 20:29:30 minidsp-shd volumio[643]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd May 14 20:29:30 minidsp-shd volumio[643]: info: ------------------------------ 99ms May 14 20:29:30 minidsp-shd volumio[643]: info: ------------------------------ 94ms May 14 20:29:30 minidsp-shd volumio[643]: info: ------------------------------ 91ms May 14 20:29:31 minidsp-shd volumio[643]: info: CoreCommandRouter::servicePushState May 14 20:29:31 minidsp-shd volumio[643]: info: CoreStateMachine::pushState May 14 20:29:31 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioPushState May 14 20:29:31 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 20:29:31 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 20:29:31 minidsp-shd volumio[643]: info: Discovery: Getting this device information May 14 20:29:31 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioGetState May 14 20:29:31 minidsp-shd volumio[643]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 20:29:36 minidsp-shd volumio[643]: info: CoreCommandRouter::volumioSeek May 14 20:29:36 minidsp-shd volumio[643]: info: CoreStateMachine::seek May 14 20:29:36 minidsp-shd volumio[643]: info: [1747247376109] inputs::seek to 0 May 14 20:29:36 minidsp-shd volumio[643]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 20:29:36 minidsp-shd volumio[643]: TypeError: this.sendSpopCommand is not a function May 14 20:29:36 minidsp-shd volumio[643]: at inputs.seek (/volumio/app/plugins/music_service/inputs/inputs_real:1:2486) May 14 20:29:36 minidsp-shd volumio[643]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1084:20) May 14 20:29:36 minidsp-shd volumio[643]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1421:28) May 14 20:29:36 minidsp-shd volumio[643]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:153:33) May 14 20:29:36 minidsp-shd volumio[643]: at Socket.emit (events.js:400:28) May 14 20:29:36 minidsp-shd volumio[643]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 14 20:29:36 minidsp-shd volumio[643]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 14 20:29:36 minidsp-shd volumio[643]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 20:29:37 minidsp-shd sudo[12702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-14 20:28 May 14 20:29:37 minidsp-shd sudo[12702]: 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"