-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Fri 2025-07-18 17:36:42 KST. -- Jul 18 17:35:00 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPlay Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::play index 49 Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::stop Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::stPlaybackTimer Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::updateTrackBlock Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrackBlock Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 48 Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:39 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:39 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 48 Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::serviceStop Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 48 Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::serviceStop Jul 18 17:35:39 volumiomostan volumio[1124]: info: [1752827739823] ControllerQobuz::stop Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:35:39 volumiomostan volumio[1124]: info: ControllerMpd::stop Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:35:39 volumiomostan volumio[1124]: info: Jul 18 17:35:39 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:39 volumiomostan volumio[1124]: info: sendMpdCommand stop took 10 milliseconds Jul 18 17:35:39 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::play index undefined Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::startPlaybackTimer Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:39 volumiomostan volumio[1124]: info: [1752827739834] ControllerQobuz::clearAddPlayTrack Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:35:39 volumiomostan volumio[1124]: info: sendMpdCommand status took 1 milliseconds Jul 18 17:35:39 volumiomostan volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 17:35:39 volumiomostan volumio[1124]: info: Jul 18 17:35:39 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:35:39 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:35:39 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:35:39 volumiomostan volumio[1124]: info: sendMpdCommand clear took 1 milliseconds Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:39 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/786351" Jul 18 17:35:39 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:39 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:39 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:39 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:39 volumiomostan volumio[1124]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 18 17:35:39 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/786351 Jul 18 17:35:39 volumiomostan volumio[1124]: info: ------------------------------ 7ms Jul 18 17:35:39 volumiomostan volumio[1124]: error: updateQueue error: null Jul 18 17:35:39 volumiomostan volumio[1124]: info: ------------------------------ 5ms Jul 18 17:35:39 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:35:39 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:35:40 volumiomostan volumio[1124]: info: getStreamUrl took 672 milliseconds Jul 18 17:35:40 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=786351&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831340&hmac=Cs3qSdGPpKU41vXwX0zy1e-rp1M Jul 18 17:35:40 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 27091370 Jul 18 17:35:40 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:35:40 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/786351" Jul 18 17:35:40 volumiomostan volumio[1124]: info: Jul 18 17:35:40 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:35:40 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:35:40 volumiomostan volumio[1124]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/786351" took 0 milliseconds Jul 18 17:35:40 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:35:40 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Jul 18 17:35:40 volumiomostan volumio[1124]: info: ------------------------------ 1ms Jul 18 17:35:40 volumiomostan volumio[1124]: info: sendMpdCommand play took 1 milliseconds Jul 18 17:35:40 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/786351 Jul 18 17:35:40 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:35:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:35:41 volumiomostan volumio[1124]: info: getStreamUrl took 742 milliseconds Jul 18 17:35:41 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=786351&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831341&hmac=8qNlh3h8XcVfTWHLDt5m9ebNYlg Jul 18 17:35:41 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 27091370 Jul 18 17:35:41 volumiomostan volumio[1124]: info: Jul 18 17:35:41 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:41 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:41 volumiomostan volumio[1124]: info: Jul 18 17:35:41 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:41 volumiomostan volumio[1124]: info: sendMpdCommand status took 3 milliseconds Jul 18 17:35:41 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:41 volumiomostan volumio[1124]: info: sendMpdCommand status took 0 milliseconds Jul 18 17:35:41 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:41 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"786351","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/786351","trackType":"qobuz"} Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: CURRENT POSITION 49 Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Jul 18 17:35:41 volumiomostan volumio[1124]: info: ------------------------------ 6ms Jul 18 17:35:41 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:41 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"786351","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/786351","trackType":"qobuz"} Jul 18 17:35:41 volumiomostan volumio[1124]: verbose: CURRENT POSITION 49 Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus play Jul 18 17:35:41 volumiomostan volumio[1124]: info: Received an update from plugin. extracting info from payload Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:41 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:41 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:41 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:41 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:41 volumiomostan volumio[1124]: info: ------------------------------ 12ms Jul 18 17:35:43 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPlay Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::play index 56 Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::stop Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::stPlaybackTimer Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::updateTrackBlock Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrackBlock Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:43 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:43 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::serviceStop Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 49 Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::serviceStop Jul 18 17:35:43 volumiomostan volumio[1124]: info: [1752827743867] ControllerQobuz::stop Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:35:43 volumiomostan volumio[1124]: info: ControllerMpd::stop Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:35:43 volumiomostan volumio[1124]: info: Jul 18 17:35:43 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:43 volumiomostan volumio[1124]: info: sendMpdCommand stop took 7 milliseconds Jul 18 17:35:43 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::play index undefined Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::startPlaybackTimer Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:43 volumiomostan volumio[1124]: info: [1752827743875] ControllerQobuz::clearAddPlayTrack Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:35:43 volumiomostan volumio[1124]: info: sendMpdCommand status took 1 milliseconds Jul 18 17:35:43 volumiomostan volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 17:35:43 volumiomostan volumio[1124]: info: Jul 18 17:35:43 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:35:43 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:35:43 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:35:43 volumiomostan volumio[1124]: info: sendMpdCommand clear took 1 milliseconds Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:43 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/49260003" Jul 18 17:35:43 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:43 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:43 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:43 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:43 volumiomostan volumio[1124]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 18 17:35:43 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/49260003 Jul 18 17:35:43 volumiomostan volumio[1124]: info: ------------------------------ 9ms Jul 18 17:35:43 volumiomostan volumio[1124]: error: updateQueue error: null Jul 18 17:35:43 volumiomostan volumio[1124]: info: ------------------------------ 8ms Jul 18 17:35:43 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:35:43 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:35:44 volumiomostan volumio[1124]: info: getStreamUrl took 663 milliseconds Jul 18 17:35:44 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=49260003&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831344&hmac=pUcGB0a0R9BP48zXvigmvETUPGg Jul 18 17:35:45 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 16877053 Jul 18 17:35:45 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:35:45 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/49260003" Jul 18 17:35:45 volumiomostan volumio[1124]: info: Jul 18 17:35:45 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:35:45 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:35:45 volumiomostan volumio[1124]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/49260003" took 3 milliseconds Jul 18 17:35:45 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:35:45 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Jul 18 17:35:45 volumiomostan volumio[1124]: info: ------------------------------ 4ms Jul 18 17:35:45 volumiomostan volumio[1124]: info: sendMpdCommand play took 1 milliseconds Jul 18 17:35:45 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/49260003 Jul 18 17:35:45 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:35:45 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:35:46 volumiomostan volumio[1124]: info: getStreamUrl took 654 milliseconds Jul 18 17:35:46 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=49260003&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831346&hmac=m62gEOkBH0OL_g6oARajfUClXyY Jul 18 17:35:46 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 16877053 Jul 18 17:35:46 volumiomostan volumio[1124]: info: Jul 18 17:35:46 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:46 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:46 volumiomostan volumio[1124]: info: Jul 18 17:35:46 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:46 volumiomostan volumio[1124]: info: sendMpdCommand status took 5 milliseconds Jul 18 17:35:46 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:46 volumiomostan volumio[1124]: info: sendMpdCommand status took 1 milliseconds Jul 18 17:35:46 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:46 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"49260003","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/49260003","trackType":"qobuz"} Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: CURRENT POSITION 56 Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Jul 18 17:35:46 volumiomostan volumio[1124]: info: ------------------------------ 7ms Jul 18 17:35:46 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:46 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"653 Kbps","isStreaming":false,"title":"49260003","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/49260003","trackType":"qobuz"} Jul 18 17:35:46 volumiomostan volumio[1124]: verbose: CURRENT POSITION 56 Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus play Jul 18 17:35:46 volumiomostan volumio[1124]: info: Received an update from plugin. extracting info from payload Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:46 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:46 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:46 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:46 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:46 volumiomostan volumio[1124]: info: ------------------------------ 14ms Jul 18 17:35:48 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioSeek Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::seek Jul 18 17:35:50 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:50 volumiomostan volumio[1124]: info: TRACKBLOCK {"album":"Country Boy","albumUri":"qobuz://album/zwd4e3hv2dgsb","albumart":"https://static.qobuz.com/images/covers/sb/dg/zwd4e3hv2dgsb_600.jpg","artist":"Don Williams","artistUri":"qobuz://artist/202746","audioQuality":"","duration":185,"explicit":false,"name":"I'm Just A Country Boy","service":"qobuz","title":"I'm Just A Country Boy","tracknumber":1,"type":"track","uri":"qobuz://song/49260003","volumeNumber":1,"tagImage":""} Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::startPlaybackTimer Jul 18 17:35:50 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:50 volumiomostan volumio[1124]: info: [1752827750171] ControllerQobuz::seek Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:35:50 volumiomostan volumio[1124]: info: ControllerMpd::seek Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:50 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:50 volumiomostan volumio[1124]: error: null Jul 18 17:35:50 volumiomostan volumio[1124]: info: Jul 18 17:35:50 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:50 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:50 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:50 volumiomostan volumio[1124]: info: sendMpdCommand status took 3 milliseconds Jul 18 17:35:50 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:50 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:50 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:35:50 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:50 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:50 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58212,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"743 Kbps","isStreaming":false,"title":"49260003","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/49260003","trackType":"qobuz"} Jul 18 17:35:50 volumiomostan volumio[1124]: verbose: CURRENT POSITION 56 Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus play Jul 18 17:35:50 volumiomostan volumio[1124]: info: Received an update from plugin. extracting info from payload Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:50 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:50 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:50 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:50 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:50 volumiomostan volumio[1124]: info: ------------------------------ 13ms Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPlay Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::play index 58 Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::stop Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::stPlaybackTimer Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::updateTrackBlock Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrackBlock Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:52 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:52 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::serviceStop Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 56 Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::serviceStop Jul 18 17:35:52 volumiomostan volumio[1124]: info: [1752827752322] ControllerQobuz::stop Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:35:52 volumiomostan volumio[1124]: info: ControllerMpd::stop Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:35:52 volumiomostan volumio[1124]: info: Jul 18 17:35:52 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:52 volumiomostan volumio[1124]: info: sendMpdCommand stop took 8 milliseconds Jul 18 17:35:52 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::play index undefined Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::startPlaybackTimer Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:35:52 volumiomostan volumio[1124]: info: [1752827752330] ControllerQobuz::clearAddPlayTrack Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:35:52 volumiomostan volumio[1124]: info: sendMpdCommand status took 0 milliseconds Jul 18 17:35:52 volumiomostan volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 17:35:52 volumiomostan volumio[1124]: info: Jul 18 17:35:52 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:35:52 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:35:52 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:35:52 volumiomostan volumio[1124]: info: sendMpdCommand clear took 1 milliseconds Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:52 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/22061587" Jul 18 17:35:52 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:52 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:52 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:35:52 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:35:52 volumiomostan volumio[1124]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 18 17:35:52 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/22061587 Jul 18 17:35:52 volumiomostan volumio[1124]: info: ------------------------------ 9ms Jul 18 17:35:52 volumiomostan volumio[1124]: error: updateQueue error: null Jul 18 17:35:52 volumiomostan volumio[1124]: info: ------------------------------ 7ms Jul 18 17:35:52 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:35:52 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:35:53 volumiomostan volumio[1124]: info: getStreamUrl took 677 milliseconds Jul 18 17:35:53 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=22061587&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831352&hmac=vOyI2RTLK5oFBsa-P3X2m85fJzI Jul 18 17:35:54 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 29843805 Jul 18 17:35:54 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:35:54 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/22061587" Jul 18 17:35:54 volumiomostan volumio[1124]: info: Jul 18 17:35:54 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:35:54 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:35:54 volumiomostan volumio[1124]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/22061587" took 0 milliseconds Jul 18 17:35:54 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:35:54 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Jul 18 17:35:54 volumiomostan volumio[1124]: info: ------------------------------ 1ms Jul 18 17:35:54 volumiomostan volumio[1124]: info: sendMpdCommand play took 0 milliseconds Jul 18 17:35:54 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/22061587 Jul 18 17:35:54 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:35:54 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:35:55 volumiomostan volumio[1124]: info: getStreamUrl took 662 milliseconds Jul 18 17:35:55 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=22061587&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831354&hmac=B3cmpGTjsYi8xjR7t-n-2nuE07k Jul 18 17:35:55 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 29843805 Jul 18 17:35:55 volumiomostan volumio[1124]: info: Jul 18 17:35:55 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:55 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:55 volumiomostan volumio[1124]: info: sendMpdCommand status took 5 milliseconds Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:55 volumiomostan volumio[1124]: info: Jul 18 17:35:55 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:35:55 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:35:55 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:55 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"22061587","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/22061587","trackType":"qobuz"} Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: CURRENT POSITION 58 Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Jul 18 17:35:55 volumiomostan volumio[1124]: info: ------------------------------ 6ms Jul 18 17:35:55 volumiomostan volumio[1124]: info: sendMpdCommand status took 1 milliseconds Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:35:55 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:35:55 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"22061587","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/22061587","trackType":"qobuz"} Jul 18 17:35:55 volumiomostan volumio[1124]: verbose: CURRENT POSITION 58 Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus play Jul 18 17:35:55 volumiomostan volumio[1124]: info: Received an update from plugin. extracting info from payload Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:55 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:35:55 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:35:55 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:35:55 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:35:55 volumiomostan volumio[1124]: info: ------------------------------ 12ms Jul 18 17:35:57 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPlay Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::play index 35 Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::stop Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::stPlaybackTimer Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::updateTrackBlock Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrackBlock Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:16 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:16 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::serviceStop Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 58 Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::serviceStop Jul 18 17:36:16 volumiomostan volumio[1124]: info: [1752827776094] ControllerQobuz::stop Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:36:16 volumiomostan volumio[1124]: info: ControllerMpd::stop Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:36:16 volumiomostan volumio[1124]: info: Jul 18 17:36:16 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:16 volumiomostan volumio[1124]: info: sendMpdCommand stop took 8 milliseconds Jul 18 17:36:16 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::play index undefined Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::startPlaybackTimer Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:16 volumiomostan volumio[1124]: info: [1752827776103] ControllerQobuz::clearAddPlayTrack Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:36:16 volumiomostan volumio[1124]: info: sendMpdCommand status took 1 milliseconds Jul 18 17:36:16 volumiomostan volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 17:36:16 volumiomostan volumio[1124]: info: Jul 18 17:36:16 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:36:16 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:36:16 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:36:16 volumiomostan volumio[1124]: info: sendMpdCommand clear took 0 milliseconds Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:16 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/188727" Jul 18 17:36:16 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:16 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:16 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:16 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:16 volumiomostan volumio[1124]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jul 18 17:36:16 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/188727 Jul 18 17:36:16 volumiomostan volumio[1124]: info: ------------------------------ 8ms Jul 18 17:36:16 volumiomostan volumio[1124]: error: updateQueue error: null Jul 18 17:36:16 volumiomostan volumio[1124]: info: ------------------------------ 8ms Jul 18 17:36:16 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:36:16 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:36:16 volumiomostan volumio[1124]: info: getStreamUrl took 700 milliseconds Jul 18 17:36:16 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=188727&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831376&hmac=XMJlgg4sVYi71DHP6CwYUXT0in4 Jul 18 17:36:18 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 12888657 Jul 18 17:36:18 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:36:18 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188727" Jul 18 17:36:18 volumiomostan volumio[1124]: info: Jul 18 17:36:18 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:36:18 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:36:18 volumiomostan volumio[1124]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188727" took 1 milliseconds Jul 18 17:36:18 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:36:18 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Jul 18 17:36:18 volumiomostan volumio[1124]: info: ------------------------------ 0ms Jul 18 17:36:18 volumiomostan volumio[1124]: info: sendMpdCommand play took 1 milliseconds Jul 18 17:36:18 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/188727 Jul 18 17:36:18 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:36:18 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:36:18 volumiomostan volumio[1124]: info: getStreamUrl took 723 milliseconds Jul 18 17:36:18 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=188727&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831378&hmac=LFqq1C-GQ2xRMA_OFnKPEVYvy-Y Jul 18 17:36:19 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 12888657 Jul 18 17:36:19 volumiomostan volumio[1124]: info: Jul 18 17:36:19 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:19 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:19 volumiomostan volumio[1124]: info: Jul 18 17:36:19 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:19 volumiomostan volumio[1124]: info: sendMpdCommand status took 4 milliseconds Jul 18 17:36:19 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:19 volumiomostan volumio[1124]: info: sendMpdCommand status took 0 milliseconds Jul 18 17:36:19 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:19 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188727","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188727","trackType":"qobuz"} Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: CURRENT POSITION 35 Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Jul 18 17:36:19 volumiomostan volumio[1124]: info: ------------------------------ 5ms Jul 18 17:36:19 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:19 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188727","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188727","trackType":"qobuz"} Jul 18 17:36:19 volumiomostan volumio[1124]: verbose: CURRENT POSITION 35 Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus play Jul 18 17:36:19 volumiomostan volumio[1124]: info: Received an update from plugin. extracting info from payload Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:19 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:19 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:19 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:19 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:19 volumiomostan volumio[1124]: info: ------------------------------ 15ms Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPlay Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::play index 34 Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::stop Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::stPlaybackTimer Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::updateTrackBlock Jul 18 17:36:20 volumiomostan volumio[1124]: info: CorePlayQueue::getTrackBlock Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:20 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:20 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:20 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:20 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::serviceStop Jul 18 17:36:20 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 35 Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreCommandRouter::serviceStop Jul 18 17:36:20 volumiomostan volumio[1124]: info: [1752827780637] ControllerQobuz::stop Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:36:20 volumiomostan volumio[1124]: info: ControllerMpd::stop Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:36:20 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:36:20 volumiomostan volumio[1124]: info: sendMpdCommand stop took 7 milliseconds Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::play index undefined Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:20 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 34 Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreStateMachine::startPlaybackTimer Jul 18 17:36:20 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 34 Jul 18 17:36:20 volumiomostan volumio[1124]: info: [1752827780644] ControllerQobuz::clearAddPlayTrack Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:36:20 volumiomostan volumio[1124]: info: Jul 18 17:36:20 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:20 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:20 volumiomostan volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 17:36:20 volumiomostan volumio[1124]: info: sendMpdCommand status took 0 milliseconds Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:20 volumiomostan volumio[1124]: info: Jul 18 17:36:20 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:36:20 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:36:20 volumiomostan volumio[1124]: info: sendMpdCommand clear took 1 milliseconds Jul 18 17:36:20 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/55869778" Jul 18 17:36:20 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:20 volumiomostan volumio[1124]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 17:36:20 volumiomostan volumio[1124]: info: ------------------------------ 1ms Jul 18 17:36:20 volumiomostan volumio[1124]: error: updateQueue error: null Jul 18 17:36:20 volumiomostan volumio[1124]: info: ------------------------------ 0ms Jul 18 17:36:20 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/55869778 Jul 18 17:36:20 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:36:20 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:36:21 volumiomostan volumio[1124]: info: getStreamUrl took 666 milliseconds Jul 18 17:36:21 volumiomostan volumio[1124]: error: Could not get QOBUZ Stream URL: [object Object] Jul 18 17:36:21 volumiomostan volumio[1124]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: [object Object]"} Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/55869778" Jul 18 17:36:21 volumiomostan volumio[1124]: info: Jul 18 17:36:21 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:36:21 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:36:21 volumiomostan volumio[1124]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/55869778" took 1 milliseconds Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Jul 18 17:36:21 volumiomostan volumio[1124]: info: ------------------------------ 1ms Jul 18 17:36:21 volumiomostan volumio[1124]: info: sendMpdCommand play took 0 milliseconds Jul 18 17:36:21 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/55869778 Jul 18 17:36:21 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:36:21 volumiomostan volumio[1124]: info: getStreamUrl took 646 milliseconds Jul 18 17:36:21 volumiomostan volumio[1124]: error: Could not get QOBUZ Stream URL: [object Object] Jul 18 17:36:21 volumiomostan volumio[1124]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: [object Object]"} Jul 18 17:36:21 volumiomostan volumio[1124]: info: Jul 18 17:36:21 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:21 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:21 volumiomostan volumio[1124]: error: MPD Playback Error: Failed to decode stream, moving to next track Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 17:36:21 volumiomostan volumio[1124]: info: sendMpdCommand status took 3 milliseconds Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:21 volumiomostan volumio[1124]: info: sendMpdCommand clearerror took 1 milliseconds Jul 18 17:36:21 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:21 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:21 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:21 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 34 Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"55869778","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/55869778","trackType":"qobuz"} Jul 18 17:36:21 volumiomostan volumio[1124]: verbose: CURRENT POSITION 34 Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService stop Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:21 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:21 volumiomostan volumio[1124]: info: No code Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:21 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:21 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:21 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:21 volumiomostan volumio[1124]: info: ------------------------------ 18ms Jul 18 17:36:22 volumiomostan volumio[1124]: info: Moving to next track after playback error Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPlay Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreStateMachine::play index 50 Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreStateMachine::stop Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreStateMachine::play index undefined Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 17:36:22 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 50 Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreStateMachine::startPlaybackTimer Jul 18 17:36:22 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 50 Jul 18 17:36:22 volumiomostan volumio[1124]: info: [1752827782975] ControllerQobuz::clearAddPlayTrack Jul 18 17:36:22 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 17:36:22 volumiomostan volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Jul 18 17:36:22 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 17:36:22 volumiomostan volumio[1124]: info: Jul 18 17:36:22 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:36:22 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:36:22 volumiomostan volumio[1124]: info: sendMpdCommand clear took 1 milliseconds Jul 18 17:36:22 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/126220146" Jul 18 17:36:22 volumiomostan volumio[1124]: error: updateQueue error: null Jul 18 17:36:22 volumiomostan volumio[1124]: info: ------------------------------ 1ms Jul 18 17:36:22 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/126220146 Jul 18 17:36:22 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:36:22 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:36:23 volumiomostan volumio[1124]: info: getStreamUrl took 727 milliseconds Jul 18 17:36:23 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=126220146&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831383&hmac=PZ032mb2znI0cqmRGrcgZlxyBZQ Jul 18 17:36:25 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 30188426 Jul 18 17:36:25 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:36:25 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/126220146" Jul 18 17:36:25 volumiomostan volumio[1124]: info: Jul 18 17:36:25 volumiomostan volumio[1124]: ---------------------------- MPD announces system playlist update Jul 18 17:36:25 volumiomostan volumio[1124]: info: Ignoring MPD Status Update Jul 18 17:36:25 volumiomostan volumio[1124]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/126220146" took 0 milliseconds Jul 18 17:36:25 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:36:25 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Jul 18 17:36:25 volumiomostan volumio[1124]: info: ------------------------------ 1ms Jul 18 17:36:25 volumiomostan volumio[1124]: info: sendMpdCommand play took 0 milliseconds Jul 18 17:36:25 volumiomostan volumio[1124]: STREAMING PROXY: Handling url /?data=qobuz://song/126220146 Jul 18 17:36:25 volumiomostan volumio[1124]: info: Executing endpoint getStreamUrlqobuz Jul 18 17:36:25 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 18 17:36:25 volumiomostan volumio[1124]: info: getStreamUrl took 693 milliseconds Jul 18 17:36:25 volumiomostan volumio[1124]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1677507&eid=126220146&fmt=6&profile=raw&app_id=539451548&cid=1474666&etsp=1752831385&hmac=RcZqrO3vMIILqX7r8paKiQzGZj0 Jul 18 17:36:26 volumiomostan volumio[1124]: STREAMING PROXY: Response: 200, length: 30188426 Jul 18 17:36:26 volumiomostan volumio[1124]: info: Jul 18 17:36:26 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:26 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:26 volumiomostan volumio[1124]: info: Jul 18 17:36:26 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:26 volumiomostan volumio[1124]: info: sendMpdCommand status took 6 milliseconds Jul 18 17:36:26 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:26 volumiomostan volumio[1124]: info: sendMpdCommand status took 0 milliseconds Jul 18 17:36:26 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:26 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 50 Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"126220146","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/126220146","trackType":"qobuz"} Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: CURRENT POSITION 50 Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Jul 18 17:36:26 volumiomostan volumio[1124]: info: ------------------------------ 7ms Jul 18 17:36:26 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:26 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 50 Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"126220146","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/126220146","trackType":"qobuz"} Jul 18 17:36:26 volumiomostan volumio[1124]: verbose: CURRENT POSITION 50 Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService play Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus play Jul 18 17:36:26 volumiomostan volumio[1124]: info: Received an update from plugin. extracting info from payload Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:26 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:26 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:26 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:26 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:26 volumiomostan volumio[1124]: info: ------------------------------ 13ms Jul 18 17:36:28 volumiomostan volumio[1124]: STREAMING PROXY: Client dropped request, destroying Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPause Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::pause Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::stPlaybackTimer Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::servicePause Jul 18 17:36:37 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 50 Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePause Jul 18 17:36:37 volumiomostan volumio[1124]: info: [1752827797446] ControllerQobuz::pause Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 17:36:37 volumiomostan volumio[1124]: info: ControllerMpd::pause Jul 18 17:36:37 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand pause Jul 18 17:36:37 volumiomostan volumio[1124]: info: Jul 18 17:36:37 volumiomostan volumio[1124]: ---------------------------- MPD announces state update: player Jul 18 17:36:37 volumiomostan volumio[1124]: info: sendMpdCommand pause took 1 milliseconds Jul 18 17:36:37 volumiomostan volumio[1124]: info: ControllerMpd::getState Jul 18 17:36:37 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Jul 18 17:36:37 volumiomostan volumio[1124]: info: sendMpdCommand status took 1 milliseconds Jul 18 17:36:37 volumiomostan volumio[1124]: verbose: ControllerMpd::parseState Jul 18 17:36:37 volumiomostan volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 17:36:37 volumiomostan volumio[1124]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 18 17:36:37 volumiomostan volumio[1124]: verbose: ControllerMpd::parseTrackInfo Jul 18 17:36:37 volumiomostan volumio[1124]: info: ControllerMpd::pushState Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreCommandRouter::servicePushState Jul 18 17:36:37 volumiomostan volumio[1124]: info: CorePlayQueue::getTrack 50 Jul 18 17:36:37 volumiomostan volumio[1124]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12721,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"637 Kbps","isStreaming":false,"title":"126220146","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/126220146","trackType":"qobuz"} Jul 18 17:36:37 volumiomostan volumio[1124]: verbose: CURRENT POSITION 50 Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::syncState stateService pause Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::syncState currentStatus pause Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::pushState Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioPushState Jul 18 17:36:37 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output update for this device Jul 18 17:36:37 volumiomostan volumio[1124]: info: MRS: Pushing multiroomSync output Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetState Jul 18 17:36:37 volumiomostan volumio[1124]: info: CoreStateMachine::stPlaybackTimer Jul 18 17:36:37 volumiomostan volumio[1124]: info: ------------------------------ 8ms Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:36:40 volumiomostan volumio[1124]: info: Retrieving Cloud Streaming UI Jul 18 17:36:40 volumiomostan volumio[1124]: info: Getting Tidal Cloud Configuration Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:36:40 volumiomostan volumio[1124]: info: Getting Qobuz Cloud Configuration Jul 18 17:36:40 volumiomostan volumio[1124]: info: Asking plugin for UI Config Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:36:40 volumiomostan volumio[1124]: info: Getting Spotify Cloud Configuration Jul 18 17:36:40 volumiomostan volumio[1124]: info: Asking plugin for UI Config Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:36:40 volumiomostan volumio[1124]: info: Saving Spotify Acccount Jul 18 17:36:40 volumiomostan volumio[1124]: info: Got it Jul 18 17:36:40 volumiomostan volumio[1124]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 18 17:36:40 volumiomostan volumio[1124]: info: Got it Jul 18 17:36:40 volumiomostan volumio[1124]: info: Got Tidal Cloud Configuration Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 17:36:40 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 18 17:36:41 volumiomostan volumio[1124]: info: Disabling MyMusic plugin upnp Jul 18 17:36:41 volumiomostan sudo[3525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 18 17:36:41 volumiomostan sudo[3525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 17:36:41 volumiomostan systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 18 17:36:41 volumiomostan volumio[1124]: error: Upnp client error: Error: This socket has been ended by the other party Jul 18 17:36:41 volumiomostan volumio[1124]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 18 17:36:42 volumiomostan volumio[1124]: info: Enabling MyMusic plugin upnp Jul 18 17:36:42 volumiomostan volumio[1124]: info: Enabling plugin upnp Jul 18 17:36:42 volumiomostan volumio[1124]: info: Loading plugin "upnp"... Jul 18 17:36:42 volumiomostan volumio[1124]: info: [1752827802178] Starting Upmpd Daemon Jul 18 17:36:42 volumiomostan volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 17:36:42 volumiomostan volumio[1124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 17:36:42 volumiomostan volumio[1124]: Error: listen EADDRINUSE: address already in use :::6599 Jul 18 17:36:42 volumiomostan volumio[1124]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jul 18 17:36:42 volumiomostan volumio[1124]: at listenInCluster (net.js:1366:12) Jul 18 17:36:42 volumiomostan volumio[1124]: at Server.listen (net.js:1452:7) Jul 18 17:36:42 volumiomostan volumio[1124]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 18 17:36:42 volumiomostan volumio[1124]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 18 17:36:42 volumiomostan volumio[1124]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 18 17:36:42 volumiomostan volumio[1124]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 17:36:42 volumiomostan volumio[1124]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 18 17:36:42 volumiomostan volumio[1124]: code: 'EADDRINUSE', Jul 18 17:36:42 volumiomostan volumio[1124]: errno: -98, Jul 18 17:36:42 volumiomostan volumio[1124]: syscall: 'listen', Jul 18 17:36:42 volumiomostan volumio[1124]: address: '::', Jul 18 17:36:42 volumiomostan volumio[1124]: port: 6599 Jul 18 17:36:42 volumiomostan volumio[1124]: } Jul 18 17:36:42 volumiomostan volumio[1124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 17:36:42 volumiomostan sudo[3536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 17:35 Jul 18 17:36:42 volumiomostan sudo[3536]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"