-- Logs begin at Thu 2019-02-14 10:12:10 UTC, end at Tue 2024-09-03 19:22:42 UTC. -- Sep 03 19:21:18 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 19:21:18 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 19:21:18 music volumio[942]: info: Discovery: Getting this device information Sep 03 19:21:18 music volumio[942]: info: CoreCommandRouter::volumioGetState Sep 03 19:21:18 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 19:21:33 music volumio[942]: info: Preload queue cleared Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::ClearQueue Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::stop Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 03 19:21:33 music volumio[942]: info: CorePlayQueue::getTrackBlock Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:33 music volumio[942]: info: CorePlayQueue::getTrack 6 Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::serviceStop Sep 03 19:21:33 music volumio[942]: info: CorePlayQueue::getTrack 6 Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::serviceStop Sep 03 19:21:33 music volumio[942]: info: [1725391293614] ControllerQobuz::stop Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 19:21:33 music volumio[942]: info: ControllerMpd::stop Sep 03 19:21:33 music volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 19:21:33 music volumio[942]: info: CorePlayQueue::clearPlayQueue Sep 03 19:21:33 music volumio[942]: info: CorePlayQueue::saveQueue Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::addQueueItems Sep 03 19:21:33 music volumio[942]: info: CorePlayQueue::addQueueItems Sep 03 19:21:33 music volumio[942]: info: Preload queue cleared Sep 03 19:21:33 music volumio[942]: info: Adding Item to queue: qobuz://album/5034202016250 Sep 03 19:21:33 music volumio[942]: info: Exploding uri qobuz://album/5034202016250 in service qobuz Sep 03 19:21:33 music volumio[942]: info: Sep 03 19:21:33 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:21:33 music volumio[942]: info: sendMpdCommand stop took 38 milliseconds Sep 03 19:21:33 music volumio[942]: info: ControllerMpd::getState Sep 03 19:21:33 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:21:33 music volumio[942]: info: sendMpdCommand status took 1 milliseconds Sep 03 19:21:33 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:21:33 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:21:33 music volumio[942]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 19:21:33 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:21:33 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:33 music volumio[942]: info: CorePlayQueue::getTrack 6 Sep 03 19:21:33 music volumio[942]: 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":"253350628","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/253350628","trackType":"qobuz"} Sep 03 19:21:33 music volumio[942]: verbose: CURRENT POSITION 6 Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::syncState stateService stop Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:33 music volumio[942]: info: No code Sep 03 19:21:33 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:33 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:33 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:33 music volumio[942]: info: ------------------------------ 24ms Sep 03 19:21:34 music volumio[942]: info: explodeUri took 712 milliseconds Sep 03 19:21:34 music volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 03 19:21:34 music volumio[942]: info: CorePlayQueue::saveQueue Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 03 19:21:34 music volumio[942]: info: CorePlayQueue::getTrackBlock Sep 03 19:21:34 music volumio[942]: info: CoreCommandRouter::volumioPlay Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::play index 0 Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::stop Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::play index undefined Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:34 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:34 music volumio[942]: info: CoreStateMachine::startPlaybackTimer Sep 03 19:21:34 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:34 music volumio[942]: info: [1725391294341] ControllerQobuz::clearAddPlayTrack Sep 03 19:21:34 music volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 19:21:34 music volumio[942]: info: sendMpdCommand stop took 2 milliseconds Sep 03 19:21:34 music volumio[942]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 19:21:34 music volumio[942]: info: Sep 03 19:21:34 music volumio[942]: ---------------------------- MPD announces system playlist update Sep 03 19:21:34 music volumio[942]: info: Ignoring MPD Status Update Sep 03 19:21:34 music volumio[942]: info: sendMpdCommand clear took 3 milliseconds Sep 03 19:21:34 music volumio[942]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/2501050" Sep 03 19:21:34 music volumio[942]: error: updateQueue error: null Sep 03 19:21:34 music volumio[942]: info: ------------------------------ 2ms Sep 03 19:21:34 music volumio[942]: STREAMING PROXY: Handling url /?data=qobuz://song/2501050 Sep 03 19:21:34 music volumio[942]: info: Executing endpoint getStreamUrlqobuz Sep 03 19:21:34 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 03 19:21:35 music volumio[942]: info: getStreamUrl took 647 milliseconds Sep 03 19:21:35 music volumio[942]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1008032&eid=2501050&fmt=6&profile=raw&app_id=539451548&cid=1179282&etsp=1725394894&hmac=CDnWZOIxE6bKPJwAYxeOf8vgIwY Sep 03 19:21:35 music volumio[942]: STREAMING PROXY: Response: 200, length: 26982605 Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2501050" Sep 03 19:21:35 music volumio[942]: STREAMING PROXY: Client dropped request, destroying Sep 03 19:21:35 music volumio[942]: info: Sep 03 19:21:35 music volumio[942]: ---------------------------- MPD announces system playlist update Sep 03 19:21:35 music volumio[942]: info: Ignoring MPD Status Update Sep 03 19:21:35 music volumio[942]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2501050" took 2 milliseconds Sep 03 19:21:35 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::sendMpdCommand play Sep 03 19:21:35 music volumio[942]: info: ------------------------------ 3ms Sep 03 19:21:35 music volumio[942]: info: sendMpdCommand play took 3 milliseconds Sep 03 19:21:35 music volumio[942]: STREAMING PROXY: Handling url /?data=qobuz://song/2501050 Sep 03 19:21:35 music volumio[942]: info: Executing endpoint getStreamUrlqobuz Sep 03 19:21:35 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 03 19:21:35 music volumio[942]: info: getStreamUrl took 530 milliseconds Sep 03 19:21:35 music volumio[942]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1008032&eid=2501050&fmt=6&profile=raw&app_id=539451548&cid=1179282&etsp=1725394895&hmac=42z4kni9CIi8VwTnYWh0H_bP7aU Sep 03 19:21:35 music volumio[942]: STREAMING PROXY: Response: 200, length: 26982605 Sep 03 19:21:35 music volumio[942]: info: Sep 03 19:21:35 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:21:35 music volumio[942]: info: ControllerMpd::getState Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:21:35 music volumio[942]: info: Sep 03 19:21:35 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:21:35 music volumio[942]: info: sendMpdCommand status took 11 milliseconds Sep 03 19:21:35 music volumio[942]: info: ControllerMpd::getState Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:21:35 music kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Sep 03 19:21:35 music volumio[942]: info: sendMpdCommand status took 3 milliseconds Sep 03 19:21:35 music volumio[942]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:21:35 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:21:35 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:21:35 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:35 music volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2501050","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2501050","trackType":"qobuz"} Sep 03 19:21:35 music volumio[942]: verbose: CURRENT POSITION 0 Sep 03 19:21:35 music volumio[942]: info: CoreStateMachine::syncState stateService play Sep 03 19:21:35 music volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 03 19:21:35 music volumio[942]: info: ------------------------------ 20ms Sep 03 19:21:35 music volumio[942]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 19:21:35 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:21:35 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:21:35 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:21:35 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:35 music volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1097,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"2501050","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2501050","trackType":"qobuz"} Sep 03 19:21:35 music volumio[942]: verbose: CURRENT POSITION 0 Sep 03 19:21:35 music volumio[942]: info: CoreStateMachine::syncState stateService play Sep 03 19:21:35 music volumio[942]: info: CoreStateMachine::syncState currentStatus play Sep 03 19:21:35 music volumio[942]: info: Received an update from plugin. extracting info from payload Sep 03 19:21:35 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:35 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:21:35 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:35 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:35 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:35 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:35 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:35 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:35 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:35 music volumio[942]: info: ------------------------------ 37ms Sep 03 19:21:39 music volumio[942]: info: Preload queue cleared Sep 03 19:21:39 music volumio[942]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::ClearQueue Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::stop Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::getTrackBlock Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:39 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:21:39 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:39 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:39 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::serviceStop Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:39 music volumio[942]: info: CoreCommandRouter::serviceStop Sep 03 19:21:39 music volumio[942]: info: [1725391299982] ControllerQobuz::stop Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 19:21:39 music volumio[942]: info: ControllerMpd::stop Sep 03 19:21:39 music volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::clearPlayQueue Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::saveQueue Sep 03 19:21:39 music volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::addQueueItems Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::addQueueItems Sep 03 19:21:39 music volumio[942]: info: Preload queue cleared Sep 03 19:21:39 music volumio[942]: info: Adding Item to queue: qobuz://album/5034202016250 Sep 03 19:21:39 music volumio[942]: info: Using cached record of: qobuz://album/5034202016250 Sep 03 19:21:39 music volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 03 19:21:39 music volumio[942]: STREAMING PROXY: Client dropped request, destroying Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::saveQueue Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 03 19:21:39 music volumio[942]: info: CorePlayQueue::getTrackBlock Sep 03 19:21:39 music volumio[942]: info: CoreCommandRouter::volumioPlay Sep 03 19:21:39 music volumio[942]: info: CoreStateMachine::play index 0 Sep 03 19:21:40 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:40 music volumio[942]: info: CoreStateMachine::stop Sep 03 19:21:40 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:40 music volumio[942]: info: CoreStateMachine::play index undefined Sep 03 19:21:40 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:21:40 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:40 music volumio[942]: info: CoreStateMachine::startPlaybackTimer Sep 03 19:21:40 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:40 music volumio[942]: info: [1725391300000] ControllerQobuz::clearAddPlayTrack Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 19:21:40 music volumio[942]: info: Sep 03 19:21:40 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:21:40 music volumio[942]: info: sendMpdCommand stop took 34 milliseconds Sep 03 19:21:40 music volumio[942]: info: ControllerMpd::getState Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:21:40 music volumio[942]: info: sendMpdCommand stop took 16 milliseconds Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 19:21:40 music volumio[942]: info: Sep 03 19:21:40 music volumio[942]: ---------------------------- MPD announces system playlist update Sep 03 19:21:40 music volumio[942]: info: Ignoring MPD Status Update Sep 03 19:21:40 music volumio[942]: info: sendMpdCommand status took 5 milliseconds Sep 03 19:21:40 music volumio[942]: info: sendMpdCommand clear took 5 milliseconds Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/2501050" Sep 03 19:21:40 music volumio[942]: STREAMING PROXY: Handling url /?data=qobuz://song/2501050 Sep 03 19:21:40 music volumio[942]: error: updateQueue error: null Sep 03 19:21:40 music volumio[942]: info: ------------------------------ 6ms Sep 03 19:21:40 music volumio[942]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:21:40 music volumio[942]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 03 19:21:40 music volumio[942]: info: ------------------------------ 13ms Sep 03 19:21:40 music volumio[942]: info: Executing endpoint getStreamUrlqobuz Sep 03 19:21:40 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 03 19:21:40 music volumio[942]: info: getStreamUrl took 575 milliseconds Sep 03 19:21:40 music volumio[942]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1008032&eid=2501050&fmt=6&profile=raw&app_id=539451548&cid=1179282&etsp=1725394900&hmac=562HfGnwbdu1N5_dvu93OgvgPJg Sep 03 19:21:40 music volumio[942]: STREAMING PROXY: Response: 200, length: 26982605 Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2501050" Sep 03 19:21:40 music volumio[942]: STREAMING PROXY: Client dropped request, destroying Sep 03 19:21:40 music volumio[942]: info: Sep 03 19:21:40 music volumio[942]: ---------------------------- MPD announces system playlist update Sep 03 19:21:40 music volumio[942]: info: Ignoring MPD Status Update Sep 03 19:21:40 music volumio[942]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2501050" took 4 milliseconds Sep 03 19:21:40 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 19:21:40 music volumio[942]: verbose: ControllerMpd::sendMpdCommand play Sep 03 19:21:40 music volumio[942]: info: ------------------------------ 5ms Sep 03 19:21:40 music volumio[942]: info: sendMpdCommand play took 2 milliseconds Sep 03 19:21:40 music volumio[942]: STREAMING PROXY: Handling url /?data=qobuz://song/2501050 Sep 03 19:21:40 music volumio[942]: info: Executing endpoint getStreamUrlqobuz Sep 03 19:21:40 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 03 19:21:41 music volumio[942]: info: getStreamUrl took 513 milliseconds Sep 03 19:21:41 music volumio[942]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1008032&eid=2501050&fmt=6&profile=raw&app_id=539451548&cid=1179282&etsp=1725394901&hmac=FlrwK3RPT6Bnhv_ewGtkPSY-SsA Sep 03 19:21:41 music volumio[942]: STREAMING PROXY: Response: 200, length: 26982605 Sep 03 19:21:41 music volumio[942]: info: Sep 03 19:21:41 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:21:41 music volumio[942]: info: ControllerMpd::getState Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:21:41 music volumio[942]: info: Sep 03 19:21:41 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:21:41 music volumio[942]: info: ControllerMpd::getState Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:21:41 music volumio[942]: info: sendMpdCommand status took 3 milliseconds Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:21:41 music volumio[942]: info: sendMpdCommand status took 4 milliseconds Sep 03 19:21:41 music volumio[942]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:21:41 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:21:41 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:21:41 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:41 music volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":411,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"2501050","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2501050","trackType":"qobuz"} Sep 03 19:21:41 music volumio[942]: verbose: CURRENT POSITION 0 Sep 03 19:21:41 music volumio[942]: info: CoreStateMachine::syncState stateService play Sep 03 19:21:41 music volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 03 19:21:41 music volumio[942]: info: ------------------------------ 11ms Sep 03 19:21:41 music volumio[942]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 19:21:41 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:21:41 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:21:41 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:21:41 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:21:41 music volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":937,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"111 Kbps","isStreaming":false,"title":"2501050","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2501050","trackType":"qobuz"} Sep 03 19:21:41 music volumio[942]: verbose: CURRENT POSITION 0 Sep 03 19:21:41 music volumio[942]: info: CoreStateMachine::syncState stateService play Sep 03 19:21:41 music volumio[942]: info: CoreStateMachine::syncState currentStatus play Sep 03 19:21:41 music volumio[942]: info: Received an update from plugin. extracting info from payload Sep 03 19:21:41 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:41 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:21:41 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:41 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:41 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:41 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:21:41 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:21:41 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:21:41 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:21:41 music volumio[942]: info: ------------------------------ 23ms Sep 03 19:21:44 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Sep 03 19:21:44 music volumio[942]: info: handleBrowseUri took 518 milliseconds Sep 03 19:21:44 music volumio[942]: info: Preload queue cleared Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501050 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501051 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501052 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501053 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501054 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501055 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501056 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501057 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501058 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501059 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501060 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501061 Sep 03 19:21:44 music volumio[942]: info: Preloading song: qobuz://song/2501062 Sep 03 19:21:44 music volumio[942]: info: Exploding uri qobuz://song/2501050 in service qobuz Sep 03 19:21:44 music volumio[942]: info: Exploding uri qobuz://song/2501051 in service qobuz Sep 03 19:21:44 music volumio[942]: info: Exploding uri qobuz://song/2501052 in service qobuz Sep 03 19:21:44 music volumio[942]: info: Exploding uri qobuz://song/2501053 in service qobuz Sep 03 19:21:44 music volumio[942]: info: Executing endpoint getSimilarAlbums Sep 03 19:21:44 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Sep 03 19:21:44 music volumio[942]: info: Exploding uri qobuz://song/2501054 in service qobuz Sep 03 19:21:44 music volumio[942]: info: Executing endpoint metavolumio Sep 03 19:21:44 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 03 19:21:44 music volumio[942]: info: Executing endpoint metavolumio Sep 03 19:21:44 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 03 19:21:44 music volumio[942]: info: Exploding uri qobuz://song/2501055 in service qobuz Sep 03 19:21:44 music volumio[942]: info: Exploding uri qobuz://song/2501056 in service qobuz Sep 03 19:21:45 music volumio[942]: info: Exploding uri qobuz://song/2501057 in service qobuz Sep 03 19:21:45 music volumio[942]: info: Exploding uri qobuz://song/2501058 in service qobuz Sep 03 19:21:45 music volumio[942]: info: Exploding uri qobuz://song/2501059 in service qobuz Sep 03 19:21:45 music volumio[942]: info: Exploding uri qobuz://song/2501060 in service qobuz Sep 03 19:21:45 music volumio[942]: info: Exploding uri qobuz://song/2501061 in service qobuz Sep 03 19:21:45 music volumio[942]: info: Exploding uri qobuz://song/2501062 in service qobuz Sep 03 19:21:45 music volumio[942]: info: explodeUri took 664 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 621 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 632 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 669 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 644 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 601 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 673 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 632 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 691 milliseconds Sep 03 19:21:45 music volumio[942]: info: explodeUri took 647 milliseconds Sep 03 19:21:46 music volumio[942]: info: explodeUri took 1107 milliseconds Sep 03 19:21:46 music volumio[942]: info: explodeUri took 1094 milliseconds Sep 03 19:21:46 music volumio[942]: info: explodeUri took 1239 milliseconds Sep 03 19:21:48 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 19:21:48 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 19:21:48 music volumio[942]: info: Discovery: Getting this device information Sep 03 19:21:48 music volumio[942]: info: CoreCommandRouter::volumioGetState Sep 03 19:21:48 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 19:21:51 music volumio[942]: STREAMING PROXY: Client dropped request, destroying Sep 03 19:21:53 music volumio[942]: Searching plugin music_service/qobuz Sep 03 19:21:53 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 03 19:21:55 music volumio[942]: info: searchQOBUZUri took 2050 milliseconds Sep 03 19:21:55 music volumio[942]: info: All search sources collected, pushing search results Sep 03 19:22:00 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Sep 03 19:22:00 music volumio[942]: info: handleBrowseUri took 627 milliseconds Sep 03 19:22:00 music volumio[942]: info: Preload queue cleared Sep 03 19:22:01 music volumio[942]: info: Executing endpoint getSimilarArtists Sep 03 19:22:01 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Sep 03 19:22:01 music volumio[942]: info: Executing endpoint metavolumio Sep 03 19:22:01 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 03 19:22:18 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 19:22:18 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 19:22:18 music volumio[942]: info: Discovery: Getting this device information Sep 03 19:22:18 music volumio[942]: info: CoreCommandRouter::volumioGetState Sep 03 19:22:18 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 19:22:26 music volumio[942]: info: Preload queue cleared Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::ClearQueue Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::stop Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::getTrackBlock Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::serviceStop Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::serviceStop Sep 03 19:22:26 music volumio[942]: info: [1725391346066] ControllerQobuz::stop Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 19:22:26 music volumio[942]: info: ControllerMpd::stop Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::clearPlayQueue Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::saveQueue Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::addQueueItems Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::addQueueItems Sep 03 19:22:26 music volumio[942]: info: Preload queue cleared Sep 03 19:22:26 music volumio[942]: info: Adding Item to queue: qobuz://album/0609008251560 Sep 03 19:22:26 music volumio[942]: info: Exploding uri qobuz://album/0609008251560 in service qobuz Sep 03 19:22:26 music volumio[942]: info: sendMpdCommand stop took 105 milliseconds Sep 03 19:22:26 music volumio[942]: info: Sep 03 19:22:26 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:22:26 music volumio[942]: info: ControllerMpd::getState Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:22:26 music volumio[942]: info: sendMpdCommand status took 0 milliseconds Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:22:26 music volumio[942]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:22:26 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:22:26 music volumio[942]: 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":"2501050","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2501050","trackType":"qobuz"} Sep 03 19:22:26 music volumio[942]: verbose: CURRENT POSITION 0 Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::syncState stateService stop Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:22:26 music volumio[942]: info: No code Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:22:26 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:22:26 music volumio[942]: info: ------------------------------ 22ms Sep 03 19:22:26 music volumio[942]: info: explodeUri took 690 milliseconds Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::saveQueue Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::getTrackBlock Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::volumioPlay Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::play index 0 Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::stop Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::play index undefined Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:22:26 music volumio[942]: info: CoreStateMachine::startPlaybackTimer Sep 03 19:22:26 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:22:26 music volumio[942]: info: [1725391346765] ControllerQobuz::clearAddPlayTrack Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 19:22:26 music volumio[942]: info: sendMpdCommand stop took 2 milliseconds Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 19:22:26 music volumio[942]: info: Sep 03 19:22:26 music volumio[942]: ---------------------------- MPD announces system playlist update Sep 03 19:22:26 music volumio[942]: info: Ignoring MPD Status Update Sep 03 19:22:26 music volumio[942]: info: sendMpdCommand clear took 3 milliseconds Sep 03 19:22:26 music volumio[942]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/2531160" Sep 03 19:22:26 music volumio[942]: error: updateQueue error: null Sep 03 19:22:26 music volumio[942]: info: ------------------------------ 2ms Sep 03 19:22:26 music volumio[942]: STREAMING PROXY: Handling url /?data=qobuz://song/2531160 Sep 03 19:22:26 music volumio[942]: info: Executing endpoint getStreamUrlqobuz Sep 03 19:22:26 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 03 19:22:27 music volumio[942]: info: getStreamUrl took 539 milliseconds Sep 03 19:22:27 music volumio[942]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1008032&eid=2531160&fmt=6&profile=raw&app_id=539451548&cid=1179282&etsp=1725394947&hmac=lTrkW4FSmKnXDFHPVromIRLtzwc Sep 03 19:22:27 music volumio[942]: STREAMING PROXY: Response: 200, length: 21408535 Sep 03 19:22:27 music volumio[942]: STREAMING PROXY: Client dropped request, destroying Sep 03 19:22:27 music volumio[942]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2531160" Sep 03 19:22:27 music volumio[942]: info: Sep 03 19:22:27 music volumio[942]: ---------------------------- MPD announces system playlist update Sep 03 19:22:27 music volumio[942]: info: Ignoring MPD Status Update Sep 03 19:22:27 music volumio[942]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2531160" took 1 milliseconds Sep 03 19:22:27 music volumio[942]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 19:22:27 music volumio[942]: verbose: ControllerMpd::sendMpdCommand play Sep 03 19:22:27 music volumio[942]: info: ------------------------------ 2ms Sep 03 19:22:27 music volumio[942]: info: sendMpdCommand play took 2 milliseconds Sep 03 19:22:27 music volumio[942]: STREAMING PROXY: Handling url /?data=qobuz://song/2531160 Sep 03 19:22:27 music volumio[942]: info: Executing endpoint getStreamUrlqobuz Sep 03 19:22:27 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 03 19:22:28 music volumio[942]: info: getStreamUrl took 533 milliseconds Sep 03 19:22:28 music volumio[942]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1008032&eid=2531160&fmt=6&profile=raw&app_id=539451548&cid=1179282&etsp=1725394947&hmac=lTrkW4FSmKnXDFHPVromIRLtzwc Sep 03 19:22:28 music volumio[942]: STREAMING PROXY: Response: 200, length: 21408535 Sep 03 19:22:28 music volumio[942]: info: Sep 03 19:22:28 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:22:28 music volumio[942]: info: ControllerMpd::getState Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:22:28 music volumio[942]: info: Sep 03 19:22:28 music volumio[942]: ---------------------------- MPD announces state update: player Sep 03 19:22:28 music volumio[942]: info: sendMpdCommand status took 4 milliseconds Sep 03 19:22:28 music volumio[942]: info: ControllerMpd::getState Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:22:28 music volumio[942]: info: sendMpdCommand status took 5 milliseconds Sep 03 19:22:28 music volumio[942]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::parseState Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:22:28 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:22:28 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:22:28 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:22:28 music volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2531160","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2531160","trackType":"qobuz"} Sep 03 19:22:28 music volumio[942]: verbose: CURRENT POSITION 0 Sep 03 19:22:28 music volumio[942]: info: CoreStateMachine::syncState stateService play Sep 03 19:22:28 music volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 03 19:22:28 music volumio[942]: info: ------------------------------ 14ms Sep 03 19:22:28 music volumio[942]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 19:22:28 music volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 03 19:22:28 music volumio[942]: info: ControllerMpd::pushState Sep 03 19:22:28 music volumio[942]: info: CoreCommandRouter::servicePushState Sep 03 19:22:28 music volumio[942]: info: CorePlayQueue::getTrack 0 Sep 03 19:22:28 music volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2531160","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2531160","trackType":"qobuz"} Sep 03 19:22:28 music volumio[942]: verbose: CURRENT POSITION 0 Sep 03 19:22:28 music volumio[942]: info: CoreStateMachine::syncState stateService play Sep 03 19:22:28 music volumio[942]: info: CoreStateMachine::syncState currentStatus play Sep 03 19:22:28 music volumio[942]: info: Received an update from plugin. extracting info from payload Sep 03 19:22:28 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:22:28 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 19:22:28 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:22:28 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:22:28 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:22:28 music volumio[942]: info: CoreStateMachine::pushState Sep 03 19:22:28 music volumio[942]: info: CoreCommandRouter::volumioPushState Sep 03 19:22:28 music volumio[942]: info: MRS: Pushing multiroomSync output update for this device Sep 03 19:22:28 music volumio[942]: info: MRS: Pushing multiroomSync output Sep 03 19:22:28 music volumio[942]: info: ------------------------------ 30ms Sep 03 19:22:29 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Sep 03 19:22:29 music volumio[942]: info: handleBrowseUri took 499 milliseconds Sep 03 19:22:29 music volumio[942]: info: Preload queue cleared Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531160 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531161 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531162 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531163 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531164 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531165 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531166 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531167 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531168 Sep 03 19:22:29 music volumio[942]: info: Preloading song: qobuz://song/2531169 Sep 03 19:22:29 music volumio[942]: info: Exploding uri qobuz://song/2531160 in service qobuz Sep 03 19:22:29 music volumio[942]: info: Exploding uri qobuz://song/2531161 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531162 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531163 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Executing endpoint getSimilarAlbums Sep 03 19:22:30 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531164 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531165 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Executing endpoint metavolumio Sep 03 19:22:30 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531166 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Executing endpoint metavolumio Sep 03 19:22:30 music volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531167 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531168 in service qobuz Sep 03 19:22:30 music volumio[942]: info: Exploding uri qobuz://song/2531169 in service qobuz Sep 03 19:22:30 music volumio[942]: info: explodeUri took 605 milliseconds Sep 03 19:22:30 music volumio[942]: info: explodeUri took 615 milliseconds Sep 03 19:22:30 music volumio[942]: info: explodeUri took 620 milliseconds Sep 03 19:22:30 music volumio[942]: info: explodeUri took 636 milliseconds Sep 03 19:22:30 music volumio[942]: info: explodeUri took 698 milliseconds Sep 03 19:22:30 music volumio[942]: info: explodeUri took 732 milliseconds Sep 03 19:22:30 music volumio[942]: info: explodeUri took 660 milliseconds Sep 03 19:22:30 music volumio[942]: info: explodeUri took 664 milliseconds Sep 03 19:22:31 music volumio[942]: info: explodeUri took 660 milliseconds Sep 03 19:22:36 music volumio[942]: STREAMING PROXY: Client dropped request, destroying Sep 03 19:22:41 music kernel: mmc0: timeout waiting for hardware interrupt. Sep 03 19:22:41 music kernel: [2fcf506c] CMD< 19 10ea1d5 Sep 03 19:22:41 music kernel: [2fcf506f] CMDD 8 200 Sep 03 19:22:41 music kernel: [2fcf506f] SDMA aecd8d7c 816f3440 Sep 03 19:22:41 music kernel: [2fcf5071] FCM< aecd8ca8 aecd8d1c Sep 03 19:22:41 music kernel: [2fcf5071] RSP 900 0 Sep 03 19:22:41 music kernel: [2fcf5072] FCM> aecd8ca8 0 Sep 03 19:22:41 music kernel: [2fcf5072] CMD 19 200 Sep 03 19:22:41 music kernel: [2fcf5072] REQ> aecd8ca8 0 Sep 03 19:22:41 music kernel: [2fcf52e7] DMA< aecd8d7c 0 Sep 03 19:22:41 music kernel: [2fcf52e8] DMA 99 10801 Sep 03 19:22:41 music kernel: [2fcf52ea] FDA< aecd8ca8 0 Sep 03 19:22:41 music kernel: [2fcf52eb] TCM< aecd8d7c 0 Sep 03 19:22:41 music kernel: [2fcf52ec] CMD< c 0 Sep 03 19:22:41 music kernel: [2fcf52ed] TCM> aecd8d7c 0 Sep 03 19:22:41 music kernel: [2fcf52ed] FDA> aecd8ca8 aecd8d4c Sep 03 19:22:41 music kernel: [2fcf52ee] DMA> 0 0 Sep 03 19:22:41 music kernel: [2fcf5596] IRQ< 400 0 Sep 03 19:22:41 music kernel: [2fcf5596] IRQB aecd8d4c 400 Sep 03 19:22:41 music kernel: [2fcf5597] FCM< aecd8ca8 aecd8d4c Sep 03 19:22:41 music kernel: [2fcf5598] RSP c00 0 Sep 03 19:22:41 music kernel: [2fcf5599] FCM> aecd8ca8 aecd8d4c Sep 03 19:22:41 music kernel: [2fcf559a] IRQ> 0 0 Sep 03 19:22:41 music kernel: [2fcf559f] TSK< aecd8ca8 0 Sep 03 19:22:41 music kernel: [2fcf55b0] TSK> aecd8ca8 0 Sep 03 19:22:41 music kernel: [efcf55ce] REQ< aec93bc0 10801 Sep 03 19:22:41 music kernel: [efcf55cf] CMD< d 70000 Sep 03 19:22:41 music kernel: [efcf55d3] FCM< aec93bc0 aec93c2c Sep 03 19:22:41 music kernel: [efcf55d5] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf55d9] FCM> aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf55d9] CMD d 0 Sep 03 19:22:41 music kernel: [efcf55da] REQ> aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf55f1] TSK< aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf55f9] TSK> aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf5604] PRD< aecdd17c 0 Sep 03 19:22:41 music kernel: [efcf5605] PRD1 816f3440 0 Sep 03 19:22:41 music kernel: [efcf560b] PRD2 1 0 Sep 03 19:22:41 music kernel: [efcf5613] PRD3 981a1804 0 Sep 03 19:22:41 music kernel: [efcf5614] PDM> aecdd17c 0 Sep 03 19:22:41 music kernel: [efcf5615] REQ< aecdd0a8 10801 Sep 03 19:22:41 music kernel: [efcf5615] CMD< 19 d6d0ed Sep 03 19:22:41 music kernel: [efcf5617] CMDD 8 200 Sep 03 19:22:41 music kernel: [efcf5617] SDMA aecdd17c 816f3440 Sep 03 19:22:41 music kernel: [efcf5619] FCM< aecdd0a8 aecdd11c Sep 03 19:22:41 music kernel: [efcf561a] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf561a] FCM> aecdd0a8 0 Sep 03 19:22:41 music kernel: [efcf561a] CMD 19 200 Sep 03 19:22:41 music kernel: [efcf561a] REQ> aecdd0a8 0 Sep 03 19:22:41 music kernel: [2fcf58a6] DMA< aecdd17c 0 Sep 03 19:22:41 music kernel: [2fcf58a7] DMA 99 10801 Sep 03 19:22:41 music kernel: [2fcf58a9] FDA< aecdd0a8 0 Sep 03 19:22:41 music kernel: [2fcf58a9] TCM< aecdd17c 0 Sep 03 19:22:41 music kernel: [2fcf58aa] CMD< c 0 Sep 03 19:22:41 music kernel: [2fcf58ab] TCM> aecdd17c 0 Sep 03 19:22:41 music kernel: [2fcf58ab] FDA> aecdd0a8 aecdd14c Sep 03 19:22:41 music kernel: [2fcf58ac] DMA> 0 0 Sep 03 19:22:41 music kernel: [2fcf6089] IRQ< 400 0 Sep 03 19:22:41 music kernel: [2fcf608a] IRQB aecdd14c 400 Sep 03 19:22:41 music kernel: [2fcf608a] FCM< aecdd0a8 aecdd14c Sep 03 19:22:41 music kernel: [2fcf608b] RSP c00 0 Sep 03 19:22:41 music kernel: [2fcf608c] FCM> aecdd0a8 aecdd14c Sep 03 19:22:41 music kernel: [2fcf608c] IRQ> 0 0 Sep 03 19:22:41 music kernel: [2fcf6091] TSK< aecdd0a8 0 Sep 03 19:22:41 music kernel: [2fcf60b2] TSK> aecdd0a8 0 Sep 03 19:22:41 music kernel: [2fcf60d4] REQ< aec95da8 10801 Sep 03 19:22:41 music kernel: [2fcf60d4] CMD< d 70000 Sep 03 19:22:41 music kernel: [2fcf60d9] FCM< aec95da8 aec95e14 Sep 03 19:22:41 music kernel: [2fcf60db] RSP 900 0 Sep 03 19:22:41 music kernel: [2fcf60e3] FCM> aec95da8 0 Sep 03 19:22:41 music kernel: [2fcf60e3] CMD d 0 Sep 03 19:22:41 music kernel: [2fcf60e4] REQ> aec95da8 0 Sep 03 19:22:41 music kernel: [2fcf6100] TSK< aec95da8 0 Sep 03 19:22:41 music kernel: [2fcf610a] TSK> aec95da8 0 Sep 03 19:22:41 music kernel: [efcf78ca] PRD< aecdd37c 0 Sep 03 19:22:41 music kernel: [efcf78cb] PRD1 816f3440 0 Sep 03 19:22:41 music kernel: [efcf78d1] PRD2 1 0 Sep 03 19:22:41 music kernel: [efcf78d9] PRD3 981a1b84 0 Sep 03 19:22:41 music kernel: [efcf78db] PDM> aecdd37c 0 Sep 03 19:22:41 music kernel: [efcf78dc] REQ< aecdd2a8 10801 Sep 03 19:22:41 music kernel: [efcf78dd] CMD< 19 d6d0f5 Sep 03 19:22:41 music kernel: [efcf78e4] CMDD 8 200 Sep 03 19:22:41 music kernel: [efcf78e5] SDMA aecdd37c 816f3440 Sep 03 19:22:41 music kernel: [efcf78e7] FCM< aecdd2a8 aecdd31c Sep 03 19:22:41 music kernel: [efcf78e8] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf78e9] FCM> aecdd2a8 0 Sep 03 19:22:41 music kernel: [efcf78e9] CMD 19 200 Sep 03 19:22:41 music kernel: [efcf78e9] REQ> aecdd2a8 0 Sep 03 19:22:41 music kernel: [2fcf7f08] DMA< aecdd37c 0 Sep 03 19:22:41 music kernel: [2fcf7f09] DMA 99 10801 Sep 03 19:22:41 music kernel: [2fcf7f0d] FDA< aecdd2a8 0 Sep 03 19:22:41 music kernel: [2fcf7f0f] TCM< aecdd37c 0 Sep 03 19:22:41 music kernel: [2fcf7f11] CMD< c 0 Sep 03 19:22:41 music kernel: [2fcf7f13] TCM> aecdd37c 0 Sep 03 19:22:41 music kernel: [2fcf7f14] FDA> aecdd2a8 aecdd34c Sep 03 19:22:41 music kernel: [2fcf7f15] DMA> 0 0 Sep 03 19:22:41 music kernel: [2fcf864c] IRQ< 400 0 Sep 03 19:22:41 music kernel: [2fcf864c] IRQB aecdd34c 400 Sep 03 19:22:41 music kernel: [2fcf864d] FCM< aecdd2a8 aecdd34c Sep 03 19:22:41 music kernel: [2fcf864e] RSP c00 0 Sep 03 19:22:41 music kernel: [2fcf864f] FCM> aecdd2a8 aecdd34c Sep 03 19:22:41 music kernel: [2fcf864f] IRQ> 0 0 Sep 03 19:22:41 music kernel: [2fcf8653] TSK< aecdd2a8 0 Sep 03 19:22:41 music kernel: [2fcf8665] TSK> aecdd2a8 0 Sep 03 19:22:41 music kernel: [efcf867f] REQ< aec93bc0 10801 Sep 03 19:22:41 music kernel: [efcf8680] CMD< d 70000 Sep 03 19:22:41 music kernel: [efcf8684] FCM< aec93bc0 aec93c2c Sep 03 19:22:41 music kernel: [efcf8686] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf8689] FCM> aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf868a] CMD d 0 Sep 03 19:22:41 music kernel: [efcf868a] REQ> aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf86a0] TSK< aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf86a8] TSK> aec93bc0 0 Sep 03 19:22:41 music kernel: [efcf86b3] PRD< aecdd57c 0 Sep 03 19:22:41 music kernel: [efcf86b4] PRD1 816f3440 0 Sep 03 19:22:41 music kernel: [efcf86b8] PRD2 1 0 Sep 03 19:22:41 music kernel: [efcf86c2] PRD3 981a1d84 0 Sep 03 19:22:41 music kernel: [efcf86c3] PDM> aecdd57c 0 Sep 03 19:22:41 music kernel: [efcf86c4] REQ< aecdd4a8 10801 Sep 03 19:22:41 music kernel: [efcf86c5] CMD< 17 8 Sep 03 19:22:41 music kernel: [efcf86c6] FCM< aecdd4a8 aecdd4ec Sep 03 19:22:41 music kernel: [efcf86c9] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf86c9] CMD< 12 10b8295 Sep 03 19:22:41 music kernel: [efcf86ca] CMDD 8 200 Sep 03 19:22:41 music kernel: [efcf86ca] SDMA aecdd57c 816f3440 Sep 03 19:22:41 music kernel: [efcf86cc] FCM< aecdd4a8 aecdd51c Sep 03 19:22:41 music kernel: [efcf86cd] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf86cd] FCM> aecdd4a8 0 Sep 03 19:22:41 music kernel: [efcf86cd] FCM> aecdd4a8 0 Sep 03 19:22:41 music kernel: [efcf86cd] CMD 12 200 Sep 03 19:22:41 music kernel: [efcf86ce] REQ> aecdd4a8 0 Sep 03 19:22:41 music kernel: [2fcf8b55] DMA< aecdd57c 1 Sep 03 19:22:41 music kernel: [2fcf8b55] DMA 52 10834 Sep 03 19:22:41 music kernel: [2fcf8b59] FDA< aecdd4a8 0 Sep 03 19:22:41 music kernel: [2fcf8b5a] TCM< aecdd57c 0 Sep 03 19:22:41 music kernel: [2fcf8b5a] WTC< 10804 0 Sep 03 19:22:41 music kernel: [2fcf8b5b] WTC> 10804 0 Sep 03 19:22:41 music kernel: [2fcf8b5b] TCM> aecdd57c 0 Sep 03 19:22:41 music kernel: [2fcf8b5c] FDA> aecdd4a8 0 Sep 03 19:22:41 music kernel: [2fcf8b5c] DMA> 0 0 Sep 03 19:22:41 music kernel: [2fcf8b62] TSK< aecdd4a8 0 Sep 03 19:22:41 music kernel: [2fcf8b99] TSK> aecdd4a8 0 Sep 03 19:22:41 music kernel: [efcf9069] PRD< aecdd77c 0 Sep 03 19:22:41 music kernel: [efcf906a] PRD1 816f3440 0 Sep 03 19:22:41 music kernel: [efcf906f] PRD2 1 0 Sep 03 19:22:41 music kernel: [efcf9079] PRD3 981a1284 0 Sep 03 19:22:41 music kernel: [efcf907a] PDM> aecdd77c 0 Sep 03 19:22:41 music kernel: [efcf907c] REQ< aecdd6a8 10801 Sep 03 19:22:41 music kernel: [efcf907c] CMD< 17 8 Sep 03 19:22:41 music kernel: [efcf9082] FCM< aecdd6a8 aecdd6ec Sep 03 19:22:41 music kernel: [efcf9085] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf9085] CMD< 12 10b829d Sep 03 19:22:41 music kernel: [efcf9087] CMDD 8 200 Sep 03 19:22:41 music kernel: [efcf9089] SDMA aecdd77c 816f3440 Sep 03 19:22:41 music kernel: [efcf908b] FCM< aecdd6a8 aecdd71c Sep 03 19:22:41 music kernel: [efcf908c] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf908d] FCM> aecdd6a8 0 Sep 03 19:22:41 music kernel: [efcf908d] FCM> aecdd6a8 0 Sep 03 19:22:41 music kernel: [efcf908e] CMD 12 200 Sep 03 19:22:41 music kernel: [efcf908e] REQ> aecdd6a8 0 Sep 03 19:22:41 music kernel: [2fcf92f8] DMA< aecdd77c 1 Sep 03 19:22:41 music kernel: [2fcf92f8] DMA 52 10834 Sep 03 19:22:41 music kernel: [2fcf92fb] FDA< aecdd6a8 0 Sep 03 19:22:41 music kernel: [2fcf92fc] TCM< aecdd77c 0 Sep 03 19:22:41 music kernel: [2fcf92fd] WTC< 10804 0 Sep 03 19:22:41 music kernel: [2fcf92fd] WTC> 10804 0 Sep 03 19:22:41 music kernel: [2fcf92fe] TCM> aecdd77c 0 Sep 03 19:22:41 music kernel: [2fcf92fe] FDA> aecdd6a8 0 Sep 03 19:22:41 music kernel: [2fcf92fe] DMA> 0 0 Sep 03 19:22:41 music kernel: [2fcf930a] TSK< aecdd6a8 0 Sep 03 19:22:41 music kernel: [2fcf931c] TSK> aecdd6a8 0 Sep 03 19:22:41 music kernel: [efcf9573] PRD< aecdd97c 0 Sep 03 19:22:41 music kernel: [efcf9574] PRD1 816f3440 0 Sep 03 19:22:41 music kernel: [efcf9578] PRD2 1 0 Sep 03 19:22:41 music kernel: [efcf9580] PRD3 981a1104 0 Sep 03 19:22:41 music kernel: [efcf9581] PDM> aecdd97c 0 Sep 03 19:22:41 music kernel: [efcf9583] REQ< aecdd8a8 10801 Sep 03 19:22:41 music kernel: [efcf9584] CMD< 17 8 Sep 03 19:22:41 music kernel: [efcf9588] FCM< aecdd8a8 aecdd8ec Sep 03 19:22:41 music kernel: [efcf958a] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf958b] CMD< 12 d3859d Sep 03 19:22:41 music kernel: [efcf958c] CMDD 8 200 Sep 03 19:22:41 music kernel: [efcf958c] SDMA aecdd97c 816f3440 Sep 03 19:22:41 music kernel: [efcf958e] FCM< aecdd8a8 aecdd91c Sep 03 19:22:41 music kernel: [efcf958f] RSP 900 0 Sep 03 19:22:41 music kernel: [efcf958f] FCM> aecdd8a8 0 Sep 03 19:22:41 music kernel: [efcf958f] FCM> aecdd8a8 0 Sep 03 19:22:41 music kernel: [efcf9590] CMD 12 200 Sep 03 19:22:41 music kernel: [efcf9590] REQ> aecdd8a8 0 Sep 03 19:22:41 music kernel: [2fcf97f0] DMA< aecdd97c 1 Sep 03 19:22:41 music kernel: [2fcf97f0] DMA 52 10834 Sep 03 19:22:41 music kernel: [2fcf97f3] FDA< aecdd8a8 0 Sep 03 19:22:41 music kernel: [2fcf97f3] TCM< aecdd97c 0 Sep 03 19:22:41 music kernel: [2fcf97f4] WTC< 10804 0 Sep 03 19:22:41 music kernel: [2fcf97f4] WTC> 10804 0 Sep 03 19:22:41 music kernel: [2fcf97f5] TCM> aecdd97c 0 Sep 03 19:22:41 music kernel: [2fcf97f5] FDA> aecdd8a8 0 Sep 03 19:22:41 music kernel: [2fcf97f5] DMA> 0 0 Sep 03 19:22:41 music kernel: [2fcf97fc] TSK< aecdd8a8 0 Sep 03 19:22:41 music kernel: [2fcf980c] TSK> aecdd8a8 0 Sep 03 19:22:41 music kernel: [efcfaa3a] PRD< aecddb7c 0 Sep 03 19:22:41 music kernel: [efcfaa3b] PRD1 816f3440 0 Sep 03 19:22:41 music kernel: [efcfaa41] PRD2 1 0 Sep 03 19:22:41 music kernel: [efcfaa4b] PRD3 981a1104 0 Sep 03 19:22:41 music kernel: [efcfaa4c] PDM> aecddb7c 0 Sep 03 19:22:41 music kernel: [efcfaa4e] REQ< aecddaa8 10801 Sep 03 19:22:41 music kernel: [efcfaa4f] CMD< 19 d6d0fd Sep 03 19:22:41 music kernel: [efcfaa55] CMDD 8 200 Sep 03 19:22:41 music kernel: [efcfaa56] SDMA aecddb7c 816f3440 Sep 03 19:22:41 music kernel: [efcfaa58] FCM< aecddaa8 aecddb1c Sep 03 19:22:41 music kernel: [efcfaa5a] RSP 900 0 Sep 03 19:22:41 music kernel: [efcfaa5a] FCM> aecddaa8 0 Sep 03 19:22:41 music kernel: [efcfaa5b] CMD 19 200 Sep 03 19:22:41 music kernel: [efcfaa5b] REQ> aecddaa8 0 Sep 03 19:22:41 music kernel: [2fcfac2c] DMA< aecddb7c 0 Sep 03 19:22:41 music kernel: [2fcfac2d] DMA 99 10801 Sep 03 19:22:41 music kernel: [2fcfac2f] FDA< aecddaa8 0 Sep 03 19:22:41 music kernel: [2fcfac31] TCM< aecddb7c 0 Sep 03 19:22:41 music kernel: [2fcfac31] CMD< c 0 Sep 03 19:22:41 music kernel: [2fcfac33] TCM> aecddb7c 0 Sep 03 19:22:41 music kernel: [2fcfac34] FDA> aecddaa8 aecddb4c Sep 03 19:22:41 music kernel: [2fcfac34] DMA> 0 0 Sep 03 19:22:41 music kernel: [2fcfaf79] IRQ< 400 0 Sep 03 19:22:41 music kernel: [2fcfaf7a] IRQB aecddb4c 400 Sep 03 19:22:41 music kernel: [2fcfaf7b] FCM< aecddaa8 aecddb4c Sep 03 19:22:41 music kernel: [2fcfaf7c] RSP c00 0 Sep 03 19:22:41 music kernel: [2fcfaf7d] FCM> aecddaa8 aecddb4c Sep 03 19:22:41 music kernel: [2fcfaf7e] IRQ> 0 0 Sep 03 19:22:41 music kernel: [2fcfaf84] TSK< aecddaa8 0 Sep 03 19:22:41 music kernel: [2fcfafc9] TSK> aecddaa8 0 Sep 03 19:22:41 music kernel: [2fcfafe3] REQ< aec95da8 10801 Sep 03 19:22:41 music kernel: [2fcfafe3] CMD< d 70000 Sep 03 19:22:41 music kernel: [2fcfafe9] FCM< aec95da8 aec95e14 Sep 03 19:22:41 music kernel: [2fcfafec] RSP 900 0 Sep 03 19:22:41 music kernel: [2fcfafec] FCM> aec95da8 0 Sep 03 19:22:41 music kernel: [2fcfafed] CMD d 0 Sep 03 19:22:41 music kernel: [2fcfafed] REQ> aec95da8 0 Sep 03 19:22:41 music kernel: [2fcfb02c] TSK< aec95da8 0 Sep 03 19:22:41 music kernel: [2fcfb037] TSK> aec95da8 0 Sep 03 19:22:41 music kernel: [2fcfb771] PRD< aecd8f7c 0 Sep 03 19:22:41 music kernel: [2fcfb772] PRD1 816f3440 0 Sep 03 19:22:41 music kernel: [2fcfb785] PRD2 20 0 Sep 03 19:22:41 music kernel: [2fcfb7b2] PRD3 81596804 0 Sep 03 19:22:41 music kernel: [2fcfb7b3] PDM> aecd8f7c 0 Sep 03 19:22:41 music kernel: [2fcfb7b5] REQ< aecd8ea8 10801 Sep 03 19:22:41 music kernel: [2fcfb7b6] CMD< 17 100 Sep 03 19:22:41 music kernel: [2fcfb7bb] FCM< aecd8ea8 aecd8eec Sep 03 19:22:41 music kernel: [2fcfb7bd] RSP 900 0 Sep 03 19:22:41 music kernel: [2fcfb7be] CMD< 12 1129fc5 Sep 03 19:22:41 music kernel: [2fcfb7bf] CMDD 100 200 Sep 03 19:22:41 music kernel: [2fcfb7bf] SDMA aecd8f7c 816f3440 Sep 03 19:22:41 music kernel: [2fcfb7c0] FCM< aecd8ea8 aecd8f1c Sep 03 19:22:41 music kernel: [2fcfb7c1] RSP 900 0 Sep 03 19:22:41 music kernel: [2fcfb7c2] FCM> aecd8ea8 0 Sep 03 19:22:41 music kernel: [2fcfb7c2] FCM> aecd8ea8 0 Sep 03 19:22:41 music kernel: [2fcfb7c2] CMD 12 200 Sep 03 19:22:41 music kernel: [2fcfb7c2] REQ> aecd8ea8 0 Sep 03 19:22:41 music kernel: [306d1240] TIM< 0 0 Sep 03 19:22:41 music kernel: mmc0: sbc op 23 arg 0x100 flags 0x15 - resp 00000900 00000000 00000000 00000000, err 0 Sep 03 19:22:41 music kernel: mmc0: cmd op 18 arg 0x1129fc5 flags 0xb5 - resp 00000900 00000000 00000000 00000000, err 0 Sep 03 19:22:41 music kernel: mmc0: data blocks 100 blksz 200 - err 0 Sep 03 19:22:41 music kernel: mmc0: stop op 12 arg 0x0 flags 0x95 - resp 00000000 00000000 00000000 00000000, err 0 Sep 03 19:22:41 music kernel: mmc0: =========== REGISTER DUMP =========== Sep 03 19:22:41 music kernel: mmc0: SDCMD 0x00004052 Sep 03 19:22:41 music kernel: mmc0: SDARG 0x01129fc5 Sep 03 19:22:41 music kernel: mmc0: SDTOUT 0x017d7840 Sep 03 19:22:41 music kernel: mmc0: SDCDIV 0x00000006 Sep 03 19:22:41 music kernel: mmc0: SDRSP0 0x00000900 Sep 03 19:22:41 music kernel: mmc0: SDRSP1 0x00001269 Sep 03 19:22:41 music kernel: mmc0: SDRSP2 0x7fffffff Sep 03 19:22:41 music kernel: mmc0: SDRSP3 0x00024007 Sep 03 19:22:41 music kernel: mmc0: SDHSTS 0x00000080 Sep 03 19:22:41 music kernel: mmc0: SDVDD 0x00000001 Sep 03 19:22:41 music kernel: mmc0: SDEDM 0x00010801 Sep 03 19:22:41 music kernel: mmc0: SDHCFG 0x0000040e Sep 03 19:22:41 music kernel: mmc0: SDHBCT 0x00000200 Sep 03 19:22:41 music kernel: mmc0: SDHBLC 0x00000005 Sep 03 19:22:41 music kernel: mmc0: =========================================== Sep 03 19:22:41 music volumio[942]: error: Failed request for metavolumio API Sep 03 19:22:41 music volumio[942]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 19:22:41 music volumio[942]: Error: read ECONNRESET Sep 03 19:22:41 music volumio[942]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Sep 03 19:22:41 music volumio[942]: errno: -104, Sep 03 19:22:41 music volumio[942]: code: 'ECONNRESET', Sep 03 19:22:41 music volumio[942]: syscall: 'read' Sep 03 19:22:41 music volumio[942]: } Sep 03 19:22:41 music volumio[942]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 19:22:42 music sudo[27853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 19:21 Sep 03 19:22:42 music sudo[27853]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"