-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-10-16 20:40:37 CEST. -- Oct 16 20:39:18 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 16 20:39:19 volumio volumio[845]: info: handleBrowseUri took 245 milliseconds Oct 16 20:39:19 volumio volumio[845]: info: Preload queue cleared Oct 16 20:39:19 volumio volumio[845]: info: Preloading song: qobuz://song/188767573 Oct 16 20:39:19 volumio volumio[845]: info: Exploding uri qobuz://song/188767573 in service qobuz Oct 16 20:39:19 volumio volumio[845]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 16 20:39:19 volumio volumio[845]: info: Executing endpoint getSimilarAlbums Oct 16 20:39:19 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 16 20:39:19 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:19 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:19 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:19 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:19 volumio volumio[845]: info: explodeUri took 690 milliseconds Oct 16 20:39:22 volumio volumio[845]: info: Preload queue cleared Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::ClearQueue Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::stop Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::serviceStop Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::serviceStop Oct 16 20:39:22 volumio volumio[845]: info: [1760639962468] ControllerQobuz::stop Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:22 volumio volumio[845]: info: ControllerMpd::stop Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::saveQueue Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::addQueueItems Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::addQueueItems Oct 16 20:39:22 volumio volumio[845]: info: Preload queue cleared Oct 16 20:39:22 volumio volumio[845]: info: Adding Item to queue: qobuz://album/rq3uc6s11byqc Oct 16 20:39:22 volumio volumio[845]: info: Exploding uri qobuz://album/rq3uc6s11byqc in service qobuz Oct 16 20:39:22 volumio volumio[845]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 16 20:39:22 volumio volumio[845]: info: Oct 16 20:39:22 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:22 volumio volumio[845]: info: sendMpdCommand stop took 50 milliseconds Oct 16 20:39:22 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:22 volumio volumio[845]: info: sendMpdCommand status took 7 milliseconds Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:22 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 20 seconds. Oct 16 20:39:22 volumio volumio[845]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:22 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:22 volumio volumio[845]: 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":"60709344","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/60709344","trackType":"qobuz"} Oct 16 20:39:22 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::syncState stateService stop Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:22 volumio volumio[845]: info: No code Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:22 volumio volumio[845]: info: ------------------------------ 35ms Oct 16 20:39:22 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 16 20:39:22 volumio volumio[845]: info: explodeUri took 254 milliseconds Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::saveQueue Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioPlay Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::play index 0 Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::stop Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::play index undefined Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:22 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer Oct 16 20:39:22 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 20:39:22 volumio volumio[845]: info: [1760639962728] ControllerQobuz::clearAddPlayTrack Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:22 volumio volumio[845]: info: sendMpdCommand stop took 4 milliseconds Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 20:39:22 volumio volumio[845]: info: Oct 16 20:39:22 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:22 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:22 volumio volumio[845]: info: sendMpdCommand clear took 1 milliseconds Oct 16 20:39:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/188767573" Oct 16 20:39:22 volumio volumio[845]: error: updateQueue error: null Oct 16 20:39:22 volumio volumio[845]: info: ------------------------------ 1ms Oct 16 20:39:22 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/188767573 Oct 16 20:39:22 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:22 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:22 volumio volumio[845]: info: getStreamUrl took 199 milliseconds Oct 16 20:39:22 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=188767573&fmt=7&profile=raw&app_id=539451548&cid=1733989&etsp=1760643562&hmac=wWwHOM00gPzVWKbWED_X1cjuuRw Oct 16 20:39:23 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 56984025 Oct 16 20:39:23 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188767573" Oct 16 20:39:23 volumio volumio[845]: info: Oct 16 20:39:23 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:23 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:23 volumio volumio[845]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188767573" took 1 milliseconds Oct 16 20:39:23 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play Oct 16 20:39:23 volumio volumio[845]: info: ------------------------------ 1ms Oct 16 20:39:23 volumio volumio[845]: info: sendMpdCommand play took 1 milliseconds Oct 16 20:39:23 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/188767573 Oct 16 20:39:23 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:23 volumio volumio[845]: info: getStreamUrl took 191 milliseconds Oct 16 20:39:23 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=188767573&fmt=7&profile=raw&app_id=539451548&cid=1733989&etsp=1760643563&hmac=iKv6OjrflruqR1nbdGYy8X8nUM4 Oct 16 20:39:23 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 56984025 Oct 16 20:39:23 volumio volumio[845]: info: Oct 16 20:39:23 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:23 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:23 volumio volumio[845]: info: Oct 16 20:39:23 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:23 volumio volumio[845]: info: sendMpdCommand status took 3 milliseconds Oct 16 20:39:23 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:23 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds Oct 16 20:39:23 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:23 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:23 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:23 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188767573","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188767573","trackType":"qobuz"} Oct 16 20:39:23 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:23 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:23 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop Oct 16 20:39:23 volumio volumio[845]: info: ------------------------------ 5ms Oct 16 20:39:23 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 20:39:23 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:23 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:23 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:23 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188767573","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188767573","trackType":"qobuz"} Oct 16 20:39:23 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:23 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:23 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play Oct 16 20:39:23 volumio volumio[845]: info: Received an update from plugin. extracting info from payload Oct 16 20:39:23 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:23 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:23 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:23 volumio volumio[845]: info: ------------------------------ 14ms Oct 16 20:39:25 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:25 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:25 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:25 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:25 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:25 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::volumioNext Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::next Oct 16 20:39:26 volumio volumio[845]: info: [1760639966135] ControllerQobuz::next Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::next Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::stop Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::serviceStop Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::serviceStop Oct 16 20:39:26 volumio volumio[845]: info: [1760639966139] ControllerQobuz::stop Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:26 volumio volumio[845]: info: ControllerMpd::stop Oct 16 20:39:26 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:26 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:39:26 volumio volumio[845]: info: Oct 16 20:39:26 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:26 volumio volumio[845]: info: sendMpdCommand stop took 44 milliseconds Oct 16 20:39:26 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:26 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::play index undefined Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrack 1 Oct 16 20:39:26 volumio volumio[845]: info: sendMpdCommand status took 3 milliseconds Oct 16 20:39:26 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:26 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:26 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 20:39:26 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:26 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:26 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:26 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:26 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:26 volumio volumio[845]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 16 20:39:26 volumio volumio[845]: info: ------------------------------ 12ms Oct 16 20:39:26 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 20 seconds. Oct 16 20:39:27 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:39:27 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:39:27 volumio volumio[845]: info: Discovery: Getting this device information Oct 16 20:39:27 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:27 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:27 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:39:28 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 20:39:28 volumio volumio[845]: info: Received Get System Info Oct 16 20:39:28 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:39:28 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:39:28 volumio volumio[845]: info: Discovery: Getting this device information Oct 16 20:39:28 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:28 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:28 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:39:35 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 16 20:39:36 volumio volumio[845]: info: handleBrowseUri took 226 milliseconds Oct 16 20:39:36 volumio volumio[845]: info: Preload queue cleared Oct 16 20:39:36 volumio volumio[845]: info: Preloading song: qobuz://song/33933200 Oct 16 20:39:36 volumio volumio[845]: info: Exploding uri qobuz://song/33933200 in service qobuz Oct 16 20:39:36 volumio volumio[845]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 16 20:39:36 volumio volumio[845]: info: Executing endpoint getSimilarAlbums Oct 16 20:39:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 16 20:39:36 volumio volumio[845]: info: explodeUri took 209 milliseconds Oct 16 20:39:36 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:36 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::volumioPlay Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::play index undefined Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:38 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer Oct 16 20:39:38 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 20:39:38 volumio volumio[845]: info: [1760639978147] ControllerQobuz::clearAddPlayTrack Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand stop took 1 milliseconds Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 20:39:38 volumio volumio[845]: info: Oct 16 20:39:38 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:38 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand clear took 0 milliseconds Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/188767573" Oct 16 20:39:38 volumio volumio[845]: error: updateQueue error: null Oct 16 20:39:38 volumio volumio[845]: info: ------------------------------ 1ms Oct 16 20:39:38 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/188767573 Oct 16 20:39:38 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:38 volumio volumio[845]: info: getStreamUrl took 180 milliseconds Oct 16 20:39:38 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=188767573&fmt=7&profile=raw&app_id=539451548&cid=1733989&etsp=1760643578&hmac=CeGfnry5-YIuZerTNw2XVV6THrc Oct 16 20:39:38 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 56984025 Oct 16 20:39:38 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188767573" Oct 16 20:39:38 volumio volumio[845]: info: Oct 16 20:39:38 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:38 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188767573" took 1 milliseconds Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play Oct 16 20:39:38 volumio volumio[845]: info: ------------------------------ 1ms Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand play took 1 milliseconds Oct 16 20:39:38 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/188767573 Oct 16 20:39:38 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:38 volumio volumio[845]: info: getStreamUrl took 237 milliseconds Oct 16 20:39:38 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=188767573&fmt=7&profile=raw&app_id=539451548&cid=1733989&etsp=1760643578&hmac=CeGfnry5-YIuZerTNw2XVV6THrc Oct 16 20:39:38 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 56984025 Oct 16 20:39:38 volumio volumio[845]: info: Oct 16 20:39:38 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:38 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:38 volumio volumio[845]: info: Oct 16 20:39:38 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand status took 14 milliseconds Oct 16 20:39:38 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand status took 0 milliseconds Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:38 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:38 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:38 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188767573","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188767573","trackType":"qobuz"} Oct 16 20:39:38 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop Oct 16 20:39:38 volumio volumio[845]: info: ------------------------------ 16ms Oct 16 20:39:38 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 20:39:38 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:38 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:38 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:38 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188767573","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188767573","trackType":"qobuz"} Oct 16 20:39:38 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play Oct 16 20:39:38 volumio volumio[845]: info: Received an update from plugin. extracting info from payload Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:38 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:38 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:38 volumio volumio[845]: info: ------------------------------ 11ms Oct 16 20:39:38 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::volumioNext Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::next Oct 16 20:39:41 volumio volumio[845]: info: [1760639981747] ControllerQobuz::next Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::next Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::stop Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::serviceStop Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::serviceStop Oct 16 20:39:41 volumio volumio[845]: info: [1760639981754] ControllerQobuz::stop Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:41 volumio volumio[845]: info: ControllerMpd::stop Oct 16 20:39:41 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:41 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:39:41 volumio volumio[845]: info: Oct 16 20:39:41 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:41 volumio volumio[845]: info: sendMpdCommand stop took 43 milliseconds Oct 16 20:39:41 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:41 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::play index undefined Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrack 1 Oct 16 20:39:41 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 20 seconds. Oct 16 20:39:41 volumio volumio[845]: info: sendMpdCommand status took 7 milliseconds Oct 16 20:39:41 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:41 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:41 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 20:39:41 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:41 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:41 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:41 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:41 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:41 volumio volumio[845]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 16 20:39:41 volumio volumio[845]: info: ------------------------------ 17ms Oct 16 20:39:48 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 16 20:39:49 volumio volumio[845]: info: handleBrowseUri took 231 milliseconds Oct 16 20:39:49 volumio volumio[845]: info: Preload queue cleared Oct 16 20:39:49 volumio volumio[845]: info: Preloading song: qobuz://song/280842535 Oct 16 20:39:49 volumio volumio[845]: info: Exploding uri qobuz://song/280842535 in service qobuz Oct 16 20:39:49 volumio volumio[845]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 16 20:39:49 volumio volumio[845]: info: Executing endpoint getSimilarAlbums Oct 16 20:39:49 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 16 20:39:49 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:49 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:49 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:49 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:49 volumio volumio[845]: info: explodeUri took 410 milliseconds Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPlay Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::play index undefined Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer Oct 16 20:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 20:39:50 volumio volumio[845]: info: [1760639990460] ControllerQobuz::clearAddPlayTrack Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand stop took 1 milliseconds Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 20:39:50 volumio volumio[845]: info: Oct 16 20:39:50 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:50 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand clear took 0 milliseconds Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/188767573" Oct 16 20:39:50 volumio volumio[845]: error: updateQueue error: null Oct 16 20:39:50 volumio volumio[845]: info: ------------------------------ 2ms Oct 16 20:39:50 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/188767573 Oct 16 20:39:50 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:50 volumio volumio[845]: info: getStreamUrl took 175 milliseconds Oct 16 20:39:50 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=188767573&fmt=7&profile=raw&app_id=539451548&cid=1733989&etsp=1760643590&hmac=477JQ0E7Wpl4dACOP3AknOMcXqQ Oct 16 20:39:50 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 56984025 Oct 16 20:39:50 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188767573" Oct 16 20:39:50 volumio volumio[845]: info: Oct 16 20:39:50 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:50 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/188767573" took 1 milliseconds Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play Oct 16 20:39:50 volumio volumio[845]: info: ------------------------------ 1ms Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand play took 2 milliseconds Oct 16 20:39:50 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/188767573 Oct 16 20:39:50 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:50 volumio volumio[845]: info: getStreamUrl took 167 milliseconds Oct 16 20:39:50 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=188767573&fmt=7&profile=raw&app_id=539451548&cid=1733989&etsp=1760643590&hmac=477JQ0E7Wpl4dACOP3AknOMcXqQ Oct 16 20:39:50 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 56984025 Oct 16 20:39:50 volumio volumio[845]: info: Oct 16 20:39:50 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:50 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:50 volumio volumio[845]: info: Oct 16 20:39:50 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand status took 15 milliseconds Oct 16 20:39:50 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 20:39:50 volumio volumio[845]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:50 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:50 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:50 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188767573","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188767573","trackType":"qobuz"} Oct 16 20:39:50 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop Oct 16 20:39:50 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:50 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"188767573","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188767573","trackType":"qobuz"} Oct 16 20:39:50 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play Oct 16 20:39:50 volumio volumio[845]: info: Received an update from plugin. extracting info from payload Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:50 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:50 volumio volumio[845]: info: ------------------------------ 25ms Oct 16 20:39:50 volumio volumio[845]: info: ------------------------------ 12ms Oct 16 20:39:51 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 16 20:39:54 volumio volumio[845]: info: Preload queue cleared Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::ClearQueue Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::stop Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::serviceStop Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::serviceStop Oct 16 20:39:54 volumio volumio[845]: info: [1760639994769] ControllerQobuz::stop Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:54 volumio volumio[845]: info: ControllerMpd::stop Oct 16 20:39:54 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::saveQueue Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::addQueueItems Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::addQueueItems Oct 16 20:39:54 volumio volumio[845]: info: Preload queue cleared Oct 16 20:39:54 volumio volumio[845]: info: Adding Item to queue: qobuz://album/a6mc92p9kkg7b Oct 16 20:39:54 volumio volumio[845]: info: Exploding uri qobuz://album/a6mc92p9kkg7b in service qobuz Oct 16 20:39:54 volumio volumio[845]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 16 20:39:54 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:39:54 volumio volumio[845]: info: Oct 16 20:39:54 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:54 volumio volumio[845]: info: sendMpdCommand stop took 54 milliseconds Oct 16 20:39:54 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:54 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:54 volumio volumio[845]: info: sendMpdCommand status took 4 milliseconds Oct 16 20:39:54 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:54 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:54 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 20 seconds. Oct 16 20:39:54 volumio volumio[845]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 20:39:54 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:54 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:54 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:54 volumio volumio[845]: 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":"188767573","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/188767573","trackType":"qobuz"} Oct 16 20:39:54 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::syncState stateService stop Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:54 volumio volumio[845]: info: No code Oct 16 20:39:54 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:54 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:54 volumio volumio[845]: info: ------------------------------ 31ms Oct 16 20:39:54 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 16 20:39:55 volumio volumio[845]: info: explodeUri took 251 milliseconds Oct 16 20:39:55 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Oct 16 20:39:55 volumio volumio[845]: info: CorePlayQueue::saveQueue Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Oct 16 20:39:55 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Oct 16 20:39:55 volumio volumio[845]: info: CoreCommandRouter::volumioPlay Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::play index 0 Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::stop Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::play index undefined Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:39:55 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer Oct 16 20:39:55 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:55 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 20:39:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 20:39:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 20:39:55 volumio volumio[845]: info: [1760639995026] ControllerQobuz::clearAddPlayTrack Oct 16 20:39:55 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 20:39:55 volumio volumio[845]: info: sendMpdCommand stop took 2 milliseconds Oct 16 20:39:55 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 20:39:55 volumio volumio[845]: info: Oct 16 20:39:55 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:55 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:55 volumio volumio[845]: info: sendMpdCommand clear took 1 milliseconds Oct 16 20:39:55 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/280842535" Oct 16 20:39:55 volumio volumio[845]: error: updateQueue error: null Oct 16 20:39:55 volumio volumio[845]: info: ------------------------------ 1ms Oct 16 20:39:55 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/280842535 Oct 16 20:39:55 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:55 volumio volumio[845]: info: getStreamUrl took 179 milliseconds Oct 16 20:39:55 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=280842535&fmt=6&profile=raw&app_id=539451548&cid=1733989&etsp=1760643595&hmac=kq7QSxacwQOARNE6vjTuQDhxGwo Oct 16 20:39:55 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 17332189 Oct 16 20:39:55 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:39:55 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/280842535" Oct 16 20:39:55 volumio volumio[845]: info: Oct 16 20:39:55 volumio volumio[845]: ---------------------------- MPD announces system playlist update Oct 16 20:39:55 volumio volumio[845]: info: Ignoring MPD Status Update Oct 16 20:39:55 volumio volumio[845]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/280842535" took 1 milliseconds Oct 16 20:39:55 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 20:39:55 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play Oct 16 20:39:55 volumio volumio[845]: STREAMING PROXY: Handling url /?data=qobuz://song/280842535 Oct 16 20:39:55 volumio volumio[845]: info: ------------------------------ 4ms Oct 16 20:39:55 volumio volumio[845]: info: sendMpdCommand play took 3 milliseconds Oct 16 20:39:55 volumio volumio[845]: info: Executing endpoint getStreamUrlqobuz Oct 16 20:39:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 16 20:39:55 volumio volumio[845]: info: getStreamUrl took 327 milliseconds Oct 16 20:39:55 volumio volumio[845]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2497093&eid=280842535&fmt=6&profile=raw&app_id=539451548&cid=1733989&etsp=1760643595&hmac=kq7QSxacwQOARNE6vjTuQDhxGwo Oct 16 20:39:56 volumio volumio[845]: STREAMING PROXY: Response: 200, length: 17332189 Oct 16 20:39:56 volumio volumio[845]: info: Oct 16 20:39:56 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:56 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:56 volumio volumio[845]: info: Oct 16 20:39:56 volumio volumio[845]: ---------------------------- MPD announces state update: player Oct 16 20:39:56 volumio volumio[845]: info: sendMpdCommand status took 15 milliseconds Oct 16 20:39:56 volumio volumio[845]: info: ControllerMpd::getState Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:56 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds Oct 16 20:39:56 volumio volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::parseState Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:56 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:56 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:56 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:56 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"280842535","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/280842535","trackType":"qobuz"} Oct 16 20:39:56 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:56 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:56 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop Oct 16 20:39:56 volumio volumio[845]: info: ------------------------------ 19ms Oct 16 20:39:56 volumio volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 20:39:56 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Oct 16 20:39:56 volumio volumio[845]: info: ControllerMpd::pushState Oct 16 20:39:56 volumio volumio[845]: info: CoreCommandRouter::servicePushState Oct 16 20:39:56 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Oct 16 20:39:56 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"376 Kbps","isStreaming":false,"title":"280842535","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/280842535","trackType":"qobuz"} Oct 16 20:39:56 volumio volumio[845]: verbose: CURRENT POSITION 0 Oct 16 20:39:56 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Oct 16 20:39:56 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play Oct 16 20:39:56 volumio volumio[845]: info: Received an update from plugin. extracting info from payload Oct 16 20:39:56 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:56 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:39:56 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:56 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:56 volumio volumio[845]: info: CoreStateMachine::pushState Oct 16 20:39:56 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Oct 16 20:39:56 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:56 volumio volumio[845]: info: ------------------------------ 13ms Oct 16 20:39:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:39:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:39:57 volumio volumio[845]: info: Discovery: Getting this device information Oct 16 20:39:57 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:39:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:39:58 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:58 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:39:58 volumio volumio[845]: info: Executing endpoint metavolumio Oct 16 20:39:58 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 20:40:04 volumio volumio[845]: STREAMING PROXY: Client dropped request, destroying Oct 16 20:40:14 volumio volumio[845]: Searching all installed plugins Oct 16 20:40:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 20:40:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: , search Oct 16 20:40:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 20:40:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 16 20:40:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 16 20:40:14 volumio volumio[845]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 16 20:40:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 16 20:40:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 16 20:40:16 volumio volumio[845]: info: searchQOBUZUri took 1166 milliseconds Oct 16 20:40:16 volumio volumio[845]: info: All search sources collected, pushing search results Oct 16 20:40:36 volumio volumio[845]: Searching all installed plugins Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: , search Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 16 20:40:36 volumio volumio[845]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 16; SM-S921B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.43 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 16; SM-S921B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.43 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.2:3000 from 192.168.1.7 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 16; SM-S921B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.43 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 16 20:40:36 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 16; SM-S921B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.43 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:40:36 volumio volumio[845]: info: Discovery: Getting this device information Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:40:36 volumio volumio[845]: info: Discovery: Getting this device information Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:40:36 volumio volumio[845]: info: Si è verificato un errore durante l'interrogazione di SHOUTCAST Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetQueue Oct 16 20:40:36 volumio volumio[845]: info: CoreStateMachine::getQueue Oct 16 20:40:36 volumio volumio[845]: info: CorePlayQueue::getQueue Oct 16 20:40:36 volumio volumio[845]: info: Listing playlists Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 20:40:36 volumio volumio[845]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 20:40:36 volumio volumio[845]: info: Received Get System Info Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 20:40:36 volumio volumio[845]: info: Discovery: Getting this device information Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Oct 16 20:40:36 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 20:40:37 volumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:40:37 volumio volumio[845]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Oct 16 20:40:37 volumio volumio[845]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Oct 16 20:40:37 volumio volumio[845]: errno: -3007, Oct 16 20:40:37 volumio volumio[845]: code: 'ENOTFOUND', Oct 16 20:40:37 volumio volumio[845]: syscall: 'getaddrinfo', Oct 16 20:40:37 volumio volumio[845]: hostname: 'lastfm.freetls.fastly.net' Oct 16 20:40:37 volumio volumio[845]: } Oct 16 20:40:37 volumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:40:37 volumio sudo[10501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 20:39 Oct 16 20:40:37 volumio sudo[10501]: 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="95d9088e5dcb3a55aabfe7f209b9ae00946f3ec5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9a3927796ae9ab3a5a247866bf574288cd09ee64" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 04 Sep 2025 04:11:35 PM CEST" VOLUMIO_VERSION="3.845" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d8c10ad904a299b47c7499a1cf239286"