-- Logs begin at Thu 2024-08-22 21:29:03 CEST, end at Thu 2024-08-22 21:56:56 CEST. -- Aug 22 21:55:36 rivo volumio[3099]: info: CorePlayQueue::getTrack 0 Aug 22 21:55:36 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , goto Aug 22 21:55:36 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:36 rivo volumio[3099]: info: explodeUri took 285 milliseconds Aug 22 21:55:36 rivo volumio[3099]: info: handleBrowseUri took 226 milliseconds Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:36 rivo volumio[3099]: info: No valid Plugin REST Endpoint: getSimilarAlbums Aug 22 21:55:46 rivo volumio[3099]: info: Preload queue cleared Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::ClearQueue Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::stPlaybackTimer Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::updateTrackBlock Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::getTrackBlock Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::getTrack 0 Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::serviceStop Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::getTrack 0 Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::serviceStop Aug 22 21:55:46 rivo volumio[3099]: info: [1724356546305] ControllerQobuz::stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 22 21:55:46 rivo volumio[3099]: info: ControllerMpd::stop Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::clearPlayQueue Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::saveQueue Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushQueue Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::addQueueItems Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::addQueueItems Aug 22 21:55:46 rivo volumio[3099]: info: Preload queue cleared Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923598 Aug 22 21:55:46 rivo volumio[3099]: info: Using cached record of: qobuz://song/4923598 Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923599 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923599 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923600 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923600 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923601 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923601 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923602 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923602 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923603 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923603 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923604 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923604 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923605 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923605 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923606 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923606 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923607 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923607 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923608 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923608 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Adding Item to queue: qobuz://song/4923609 Aug 22 21:55:46 rivo volumio[3099]: info: Exploding uri qobuz://song/4923609 in service qobuz Aug 22 21:55:46 rivo volumio[3099]: https://browsing-performer.prod.vlmapi.io/volumio2/qobuz/explodeUri Aug 22 21:55:46 rivo volumio[3099]: info: Aug 22 21:55:46 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:46 rivo volumio[3099]: info: sendMpdCommand stop took 103 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:46 rivo volumio[3099]: info: sendMpdCommand status took 5 milliseconds Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:46 rivo volumio[3099]: info: Aug 22 21:55:46 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:46 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:46 rivo volumio[3099]: info: Aug 22 21:55:46 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:46 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:46 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: sendMpdCommand status took 7 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: sendMpdCommand status took 6 milliseconds Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:46 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::getTrack 0 Aug 22 21:55:46 rivo volumio[3099]: 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":"4923598","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923598","trackType":"qobuz"} Aug 22 21:55:46 rivo volumio[3099]: verbose: CURRENT POSITION 0 Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::syncState stateService stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: No code Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: ------------------------------ 70ms Aug 22 21:55:46 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 54 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 55 milliseconds Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:46 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:46 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::getTrack 0 Aug 22 21:55:46 rivo volumio[3099]: 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":"4923598","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923598","trackType":"qobuz"} Aug 22 21:55:46 rivo volumio[3099]: verbose: CURRENT POSITION 0 Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::syncState stateService stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: No code Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: CorePlayQueue::getTrack 0 Aug 22 21:55:46 rivo volumio[3099]: 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":"4923598","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923598","trackType":"qobuz"} Aug 22 21:55:46 rivo volumio[3099]: verbose: CURRENT POSITION 0 Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::syncState stateService stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus stop Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: No code Aug 22 21:55:46 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:46 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:46 rivo volumio[3099]: info: ------------------------------ 147ms Aug 22 21:55:46 rivo volumio[3099]: info: ------------------------------ 145ms Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 515 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 528 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 555 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 562 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 575 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 634 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 651 milliseconds Aug 22 21:55:46 rivo volumio[3099]: info: explodeUri took 634 milliseconds Aug 22 21:55:47 rivo volumio[3099]: info: explodeUri took 692 milliseconds Aug 22 21:55:47 rivo volumio[3099]: info: explodeUri took 687 milliseconds Aug 22 21:55:47 rivo volumio[3099]: info: explodeUri took 715 milliseconds Aug 22 21:55:47 rivo volumio[3099]: info: CoreCommandRouter::volumioPushQueue Aug 22 21:55:47 rivo volumio[3099]: info: CorePlayQueue::saveQueue Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::updateTrackBlock Aug 22 21:55:47 rivo volumio[3099]: info: CorePlayQueue::getTrackBlock Aug 22 21:55:47 rivo volumio[3099]: info: CoreCommandRouter::volumioPlay Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::play index 7 Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::stop Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::play index undefined Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:55:47 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::startPlaybackTimer Aug 22 21:55:47 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:47 rivo volumio[3099]: info: [1724356547063] ControllerQobuz::clearAddPlayTrack Aug 22 21:55:47 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 21:55:47 rivo volumio[3099]: info: sendMpdCommand stop took 12 milliseconds Aug 22 21:55:47 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 21:55:47 rivo volumio[3099]: info: Aug 22 21:55:47 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Aug 22 21:55:47 rivo volumio[3099]: info: Ignoring MPD Status Update Aug 22 21:55:47 rivo volumio[3099]: info: sendMpdCommand clear took 2 milliseconds Aug 22 21:55:47 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/4923605" Aug 22 21:55:47 rivo volumio[3099]: info: Aug 22 21:55:47 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Aug 22 21:55:47 rivo volumio[3099]: info: Ignoring MPD Status Update Aug 22 21:55:47 rivo volumio[3099]: info: Aug 22 21:55:47 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Aug 22 21:55:47 rivo volumio[3099]: STREAMING PROXY: Handling url /?data=qobuz://song/4923605 Aug 22 21:55:47 rivo volumio[3099]: info: Ignoring MPD Status Update Aug 22 21:55:47 rivo volumio[3099]: error: updateQueue error: null Aug 22 21:55:47 rivo volumio[3099]: info: ------------------------------ 8ms Aug 22 21:55:47 rivo volumio[3099]: info: Executing endpoint getStreamUrlqobuz Aug 22 21:55:47 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 22 21:55:47 rivo volumio[3099]: info: getStreamUrl took 264 milliseconds Aug 22 21:55:47 rivo volumio[3099]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2410730&eid=4923605&fmt=6&profile=raw&app_id=539451548&cid=1677071&etsp=1724360147&hmac=KEwwcHa3z_8WCo90tUo9iyZxCXE Aug 22 21:55:47 rivo volumio[3099]: STREAMING PROXY: Response: 200, length: 24082657 Aug 22 21:55:47 rivo volumio[3099]: STREAMING PROXY: Client dropped request, destroying Aug 22 21:55:47 rivo volumio[3099]: error: updateQueue error: null Aug 22 21:55:47 rivo volumio[3099]: error: updateQueue error: null Aug 22 21:55:47 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4923605" Aug 22 21:55:47 rivo volumio[3099]: info: ------------------------------ 689ms Aug 22 21:55:47 rivo volumio[3099]: info: ------------------------------ 688ms Aug 22 21:55:47 rivo volumio[3099]: info: Aug 22 21:55:47 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Aug 22 21:55:47 rivo volumio[3099]: info: Ignoring MPD Status Update Aug 22 21:55:47 rivo volumio[3099]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4923605" took 3 milliseconds Aug 22 21:55:47 rivo volumio[3099]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 22 21:55:47 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand play Aug 22 21:55:47 rivo volumio[3099]: info: Aug 22 21:55:47 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Aug 22 21:55:47 rivo volumio[3099]: info: Ignoring MPD Status Update Aug 22 21:55:47 rivo volumio[3099]: STREAMING PROXY: Handling url /?data=qobuz://song/4923605 Aug 22 21:55:47 rivo volumio[3099]: info: Aug 22 21:55:47 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Aug 22 21:55:47 rivo volumio[3099]: info: Ignoring MPD Status Update Aug 22 21:55:47 rivo volumio[3099]: info: ------------------------------ 14ms Aug 22 21:55:47 rivo volumio[3099]: info: sendMpdCommand play took 12 milliseconds Aug 22 21:55:47 rivo volumio[3099]: info: ------------------------------ 12ms Aug 22 21:55:47 rivo volumio[3099]: info: ------------------------------ 10ms Aug 22 21:55:47 rivo volumio[3099]: info: Executing endpoint getStreamUrlqobuz Aug 22 21:55:47 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 22 21:55:48 rivo volumio[3099]: info: getStreamUrl took 268 milliseconds Aug 22 21:55:48 rivo volumio[3099]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2410730&eid=4923605&fmt=6&profile=raw&app_id=539451548&cid=1677071&etsp=1724360147&hmac=KEwwcHa3z_8WCo90tUo9iyZxCXE Aug 22 21:55:48 rivo volumio[3099]: STREAMING PROXY: Response: 200, length: 24082657 Aug 22 21:55:48 rivo volumio[3099]: info: Aug 22 21:55:48 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:48 rivo volumio[3099]: info: Aug 22 21:55:48 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:48 rivo volumio[3099]: info: Aug 22 21:55:48 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand status took 21 milliseconds Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:48 rivo volumio[3099]: info: Aug 22 21:55:48 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand status took 25 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand status took 25 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:48 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:48 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4923605","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923605","trackType":"qobuz"} Aug 22 21:55:48 rivo volumio[3099]: verbose: CURRENT POSITION 7 Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus stop Aug 22 21:55:48 rivo volumio[3099]: info: ------------------------------ 54ms Aug 22 21:55:48 rivo volumio[3099]: info: Aug 22 21:55:48 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:48 rivo volumio[3099]: info: Aug 22 21:55:48 rivo volumio[3099]: ---------------------------- MPD announces state update: player Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::getState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand status took 33 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand status took 7 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand status took 5 milliseconds Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseState Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:48 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:48 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4923605","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923605","trackType":"qobuz"} Aug 22 21:55:48 rivo volumio[3099]: verbose: CURRENT POSITION 7 Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:55:48 rivo volumio[3099]: info: Received an update from plugin. extracting info from payload Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:48 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:48 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"442 Kbps","isStreaming":false,"title":"4923605","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923605","trackType":"qobuz"} Aug 22 21:55:48 rivo volumio[3099]: verbose: CURRENT POSITION 7 Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:55:48 rivo volumio[3099]: info: Received an update from plugin. extracting info from payload Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: ------------------------------ 167ms Aug 22 21:55:48 rivo volumio[3099]: info: ------------------------------ 167ms Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 108 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 106 milliseconds Aug 22 21:55:48 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 106 milliseconds Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:48 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:48 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:48 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"514 Kbps","isStreaming":false,"title":"4923605","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923605","trackType":"qobuz"} Aug 22 21:55:48 rivo volumio[3099]: verbose: CURRENT POSITION 7 Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:55:48 rivo volumio[3099]: info: Received an update from plugin. extracting info from payload Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:48 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:48 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"514 Kbps","isStreaming":false,"title":"4923605","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923605","trackType":"qobuz"} Aug 22 21:55:48 rivo volumio[3099]: verbose: CURRENT POSITION 7 Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:55:48 rivo volumio[3099]: info: Received an update from plugin. extracting info from payload Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: ControllerMpd::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Aug 22 21:55:48 rivo volumio[3099]: info: CorePlayQueue::getTrack 7 Aug 22 21:55:48 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"514 Kbps","isStreaming":false,"title":"4923605","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4923605","trackType":"qobuz"} Aug 22 21:55:48 rivo volumio[3099]: verbose: CURRENT POSITION 7 Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:55:48 rivo volumio[3099]: info: Received an update from plugin. extracting info from payload Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreStateMachine::pushState Aug 22 21:55:48 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Aug 22 21:55:48 rivo volumio[3099]: info: ------------------------------ 301ms Aug 22 21:55:48 rivo volumio[3099]: info: ------------------------------ 274ms Aug 22 21:55:48 rivo volumio[3099]: info: ------------------------------ 273ms Aug 22 21:55:50 rivo volumio[3099]: STREAMING PROXY: Client dropped request, destroying Aug 22 21:56:42 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 22 21:56:43 rivo volumio[3099]: info: handleBrowseUri took 168 milliseconds Aug 22 21:56:43 rivo volumio[3099]: info: Preload queue cleared Aug 22 21:56:43 rivo volumio[3099]: info: Preload queue cleared Aug 22 21:56:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Aug 22 21:56:55 rivo volumio[3099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 21:56:55 rivo volumio[3099]: TypeError: Cannot read property 'then' of undefined Aug 22 21:56:55 rivo volumio[3099]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Aug 22 21:56:55 rivo volumio[3099]: at Socket.emit (events.js:400:28) Aug 22 21:56:55 rivo volumio[3099]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 22 21:56:55 rivo volumio[3099]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 22 21:56:55 rivo volumio[3099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 21:56:56 rivo sudo[4690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-22 21:55 Aug 22 21:56:56 rivo sudo[4690]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 05:06:46 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="420eec03f218d056e8306370042003fc"