-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-24 20:02:22 CEST. -- May 24 20:01:03 volumio sudo[4865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 20:01:03 volumio sudo[4865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 20:01:03 volumio sudo[4865]: pam_unix(sudo:session): session closed for user root May 24 20:01:03 volumio sudo[4867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 20:01:03 volumio sudo[4867]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 20:01:03 volumio sudo[4867]: pam_unix(sudo:session): session closed for user root May 24 20:01:03 volumio volumio[884]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.30 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::volumioGetVisibleSources May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue May 24 20:01:04 volumio volumio[884]: info: CoreStateMachine::getQueue May 24 20:01:04 volumio volumio[884]: info: CorePlayQueue::getQueue May 24 20:01:04 volumio volumio[884]: info: Listing playlists May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 20:01:04 volumio volumio[884]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 20:01:04 volumio volumio[884]: info: Received Get System Info May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 20:01:04 volumio volumio[884]: info: Discovery: Getting this device information May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 20:01:04 volumio volumio[884]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 20:01:04 volumio volumio[884]: info: FusionDsp - no preset used May 24 20:01:05 volumio volumio[884]: info: FusionDsp - undefined May 24 20:01:05 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 20:01:05 volumio volumio[884]: info: Received Get System Info May 24 20:01:05 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 20:01:05 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 20:01:05 volumio volumio[884]: info: Discovery: Getting this device information May 24 20:01:05 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:05 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 20:01:05 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 24 20:01:06 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 20:01:06 volumio volumio[884]: info: Received Get System Info May 24 20:01:06 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 20:01:06 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 20:01:06 volumio volumio[884]: info: Discovery: Getting this device information May 24 20:01:06 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:06 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 20:01:10 volumio volumio[884]: info: Preload queue cleared May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::ClearQueue May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::stop May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::stPlaybackTimer May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::serviceStop May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::serviceStop May 24 20:01:10 volumio volumio[884]: info: [1748109670233] ControllerQobuz::stop May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 20:01:10 volumio volumio[884]: info: ControllerMpd::stop May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand stop May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::clearPlayQueue May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::addQueueItems May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::addQueueItems May 24 20:01:10 volumio volumio[884]: info: Preload queue cleared May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/234684809 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/234684809 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/129342731 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/129342731 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/64868955 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/64868955 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/161150239 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/161150239 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/47683549 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/47683549 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/60179388 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/60179388 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/243550180 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/243550180 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/120588227 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/120588227 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/127167037 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/127167037 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/159899471 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/159899471 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/33980847 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/33980847 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/103674227 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/103674227 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/31964067 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/31964067 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/34901954 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/34901954 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/47683835 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/47683835 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/47683562 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/47683562 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/47683565 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/47683565 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/137192575 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/137192575 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/138731318 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/138731318 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/3622571 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/3622571 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/174128482 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/174128482 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/121698765 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/121698765 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/19837170 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/19837170 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/82221341 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/82221341 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/146815656 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/146815656 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/120360021 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/120360021 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/214317200 May 24 20:01:10 volumio volumio[884]: info: Exploding uri qobuz://song/214317200 in service qobuz May 24 20:01:10 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/168167537 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/168167537 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/149883715 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/149883715 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/102910602 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/102910602 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/148025944 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/148025944 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/717925 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/717925 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/39487627 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/39487627 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/39487625 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/39487625 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/129844736 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/129844736 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/5371837 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/5371837 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/213981945 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/213981945 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/1077450 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/1077450 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/174589292 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/174589292 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/55967947 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/55967947 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/94129569 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/94129569 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/94129573 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/94129573 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/168753686 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/168753686 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/203305266 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/203305266 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/52395472 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/52395472 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/192874669 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/192874669 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/198229577 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/198229577 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/7294230 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/7294230 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/104319300 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/104319300 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/116066184 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/116066184 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/100428533 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/100428533 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/134288031 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/134288031 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/96031732 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/96031732 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/196315394 May 24 20:01:10 volumio volumio[884]: info: Exploding uri qobuz://song/196315394 in service qobuz May 24 20:01:10 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/7943036 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/7943036 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/194297042 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/194297042 May 24 20:01:10 volumio volumio[884]: info: Adding Item to queue: qobuz://song/90384454 May 24 20:01:10 volumio volumio[884]: info: Using cached record of: qobuz://song/90384454 May 24 20:01:10 volumio volumio[884]: STREAMING PROXY: Client dropped request, destroying May 24 20:01:10 volumio volumio[884]: info: May 24 20:01:10 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:10 volumio volumio[884]: info: sendMpdCommand stop took 189 milliseconds May 24 20:01:10 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:10 volumio volumio[884]: info: sendMpdCommand status took 5 milliseconds May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:10 volumio volumio[884]: info: May 24 20:01:10 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:10 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:10 volumio volumio[884]: info: sendMpdCommand playlistinfo took 5 milliseconds May 24 20:01:10 volumio volumio[884]: info: sendMpdCommand status took 3 milliseconds May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:10 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:10 volumio volumio[884]: 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":"25316308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/25316308","trackType":"qobuz"} May 24 20:01:10 volumio volumio[884]: verbose: CURRENT POSITION 0 May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::syncState stateService stop May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus stop May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:10 volumio volumio[884]: info: No code May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:10 volumio volumio[884]: info: ------------------------------ 128ms May 24 20:01:10 volumio volumio[884]: info: sendMpdCommand playlistinfo took 119 milliseconds May 24 20:01:10 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:10 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:10 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:10 volumio volumio[884]: 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":"25316308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/25316308","trackType":"qobuz"} May 24 20:01:10 volumio volumio[884]: verbose: CURRENT POSITION 0 May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::syncState stateService stop May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus stop May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:10 volumio volumio[884]: info: No code May 24 20:01:10 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:10 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:10 volumio volumio[884]: info: ------------------------------ 233ms May 24 20:01:11 volumio volumio[884]: info: explodeUri took 830 milliseconds May 24 20:01:11 volumio volumio[884]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} May 24 20:01:11 volumio volumio[884]: error: Commandrouter: Cannot explode uri qobuz://song/214317200 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} May 24 20:01:11 volumio volumio[884]: info: explodeUri took 831 milliseconds May 24 20:01:11 volumio volumio[884]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} May 24 20:01:11 volumio volumio[884]: error: Commandrouter: Cannot explode uri qobuz://song/196315394 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} May 24 20:01:11 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:11 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:11 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:11 volumio volumio[884]: info: CoreCommandRouter::volumioPlay May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::play index 56 May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::addQueueItems May 24 20:01:11 volumio volumio[884]: info: CorePlayQueue::addQueueItems May 24 20:01:11 volumio volumio[884]: info: Preload queue cleared May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/181662561 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/181662561 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/25316308 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/25316308 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/41692589 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/41692589 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/190635279 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/190635279 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/17190063 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/17190063 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/128400237 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/128400237 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/128400250 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/128400250 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/77532137 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/77532137 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/167526876 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/167526876 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/187245482 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/187245482 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/166120814 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/166120814 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/93989017 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/93989017 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/713325 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/713325 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/6219455 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/6219455 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/721350 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/721350 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/69294036 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/69294036 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/304007004 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/304007004 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/38478201 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/38478201 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/50684500 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/50684500 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/11189597 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/11189597 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/6732347 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/6732347 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/23265470 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/23265470 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/77469835 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/77469835 May 24 20:01:11 volumio volumio[884]: info: Adding Item to queue: qobuz://song/40207247 May 24 20:01:11 volumio volumio[884]: info: Using cached record of: qobuz://song/40207247 May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::stop May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:11 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:11 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::play index undefined May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:11 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:11 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::startPlaybackTimer May 24 20:01:11 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:11 volumio volumio[884]: info: [1748109671248] ControllerQobuz::clearAddPlayTrack May 24 20:01:11 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand stop May 24 20:01:11 volumio volumio[884]: info: sendMpdCommand stop took 55 milliseconds May 24 20:01:11 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand clear May 24 20:01:11 volumio volumio[884]: info: May 24 20:01:11 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:11 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:11 volumio volumio[884]: info: sendMpdCommand clear took 12 milliseconds May 24 20:01:11 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/25316308" May 24 20:01:11 volumio volumio[884]: info: May 24 20:01:11 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:11 volumio volumio[884]: STREAMING PROXY: Handling url /?data=qobuz://song/25316308 May 24 20:01:11 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:11 volumio volumio[884]: error: updateQueue error: null May 24 20:01:11 volumio volumio[884]: info: ------------------------------ 15ms May 24 20:01:11 volumio volumio[884]: info: Executing endpoint getStreamUrlqobuz May 24 20:01:11 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 24 20:01:11 volumio volumio[884]: info: getStreamUrl took 384 milliseconds May 24 20:01:11 volumio volumio[884]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=355745&eid=25316308&fmt=7&profile=raw&app_id=539451548&cid=2066930&etsp=1748113271&hmac=UUiLcN87hbZ4OlvSOChRUkKYxKI May 24 20:01:11 volumio volumio[884]: STREAMING PROXY: Response: 200, length: 121004916 May 24 20:01:11 volumio volumio[884]: error: updateQueue error: null May 24 20:01:11 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/25316308" May 24 20:01:11 volumio volumio[884]: info: ------------------------------ 540ms May 24 20:01:11 volumio volumio[884]: info: May 24 20:01:11 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:11 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:11 volumio volumio[884]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/25316308" took 3 milliseconds May 24 20:01:11 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 20:01:11 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand play May 24 20:01:11 volumio volumio[884]: STREAMING PROXY: Client dropped request, destroying May 24 20:01:11 volumio volumio[884]: info: May 24 20:01:11 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:11 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:11 volumio volumio[884]: info: ------------------------------ 8ms May 24 20:01:11 volumio volumio[884]: info: sendMpdCommand play took 6 milliseconds May 24 20:01:11 volumio volumio[884]: info: ------------------------------ 7ms May 24 20:01:11 volumio volumio[884]: STREAMING PROXY: Handling url /?data=qobuz://song/25316308 May 24 20:01:11 volumio volumio[884]: info: Executing endpoint getStreamUrlqobuz May 24 20:01:11 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 24 20:01:12 volumio volumio[884]: info: getStreamUrl took 412 milliseconds May 24 20:01:12 volumio volumio[884]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=355745&eid=25316308&fmt=7&profile=raw&app_id=539451548&cid=2066930&etsp=1748113272&hmac=YB1CELDuIXbv11JnJQM0_jjOUcM May 24 20:01:12 volumio volumio[884]: STREAMING PROXY: Response: 200, length: 121004916 May 24 20:01:12 volumio volumio[884]: info: May 24 20:01:12 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:12 volumio volumio[884]: info: May 24 20:01:12 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:12 volumio volumio[884]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 24 20:01:12 volumio volumio[884]: info: FusionDsp - ---- read samplerate from file: 96000 May 24 20:01:12 volumio volumio[884]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 24 20:01:12 volumio volumio[884]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 24 20:01:12 volumio volumio[884]: info: FusionDsp - ---- read samplerate from file: 96000 May 24 20:01:12 volumio volumio[884]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 24 20:01:12 volumio volumio[884]: info: May 24 20:01:12 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand status took 52 milliseconds May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand status took 51 milliseconds May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:12 volumio volumio[884]: info: May 24 20:01:12 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand status took 15 milliseconds May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand playlistinfo took 14 milliseconds May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand playlistinfo took 12 milliseconds May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:12 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:12 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"25316308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/25316308","trackType":"qobuz"} May 24 20:01:12 volumio volumio[884]: verbose: CURRENT POSITION 56 May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus stop May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:12 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:12 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"25316308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/25316308","trackType":"qobuz"} May 24 20:01:12 volumio volumio[884]: verbose: CURRENT POSITION 56 May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:12 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:12 volumio volumio[884]: info: ------------------------------ 117ms May 24 20:01:12 volumio volumio[884]: info: ------------------------------ 144ms May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand status took 88 milliseconds May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand playlistinfo took 79 milliseconds May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:12 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:12 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"25316308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/25316308","trackType":"qobuz"} May 24 20:01:12 volumio volumio[884]: verbose: CURRENT POSITION 56 May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:12 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:12 volumio volumio[884]: info: ------------------------------ 177ms May 24 20:01:12 volumio volumio[884]: info: sendMpdCommand playlistinfo took 163 milliseconds May 24 20:01:12 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:12 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:12 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:12 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"25316308","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/25316308","trackType":"qobuz"} May 24 20:01:12 volumio volumio[884]: verbose: CURRENT POSITION 56 May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:12 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:12 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:12 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:12 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:12 volumio volumio[884]: info: ------------------------------ 314ms May 24 20:01:12 volumio volumio[884]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 24 20:01:12 volumio volumio[884]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 24 20:01:13 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 20:01:14 volumio volumio[884]: info: Preload queue cleared May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::ClearQueue May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::stop May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::stPlaybackTimer May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:14 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:14 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::serviceStop May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 56 May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::serviceStop May 24 20:01:14 volumio volumio[884]: info: [1748109674658] ControllerQobuz::stop May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 20:01:14 volumio volumio[884]: info: ControllerMpd::stop May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand stop May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::clearPlayQueue May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::addQueueItems May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::addQueueItems May 24 20:01:14 volumio volumio[884]: info: Preload queue cleared May 24 20:01:14 volumio volumio[884]: info: Adding Item to queue: qobuz://song/90384454 May 24 20:01:14 volumio volumio[884]: info: Using cached record of: qobuz://song/90384454 May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioPlay May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::play index 0 May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::stop May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::play index undefined May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::startPlaybackTimer May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioGetVisibleSources May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 24 20:01:14 volumio volumio[884]: info: [1748109674706] ControllerQobuz::clearAddPlayTrack May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand stop May 24 20:01:14 volumio volumio[884]: STREAMING PROXY: Client dropped request, destroying May 24 20:01:14 volumio volumio[884]: info: May 24 20:01:14 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:14 volumio volumio[884]: info: sendMpdCommand stop took 81 milliseconds May 24 20:01:14 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:14 volumio volumio[884]: info: sendMpdCommand stop took 35 milliseconds May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand clear May 24 20:01:14 volumio volumio[884]: info: May 24 20:01:14 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:14 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:14 volumio volumio[884]: info: May 24 20:01:14 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:14 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:14 volumio volumio[884]: info: sendMpdCommand status took 9 milliseconds May 24 20:01:14 volumio volumio[884]: info: sendMpdCommand clear took 9 milliseconds May 24 20:01:14 volumio volumio[884]: info: sendMpdCommand status took 6 milliseconds May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/90384454" May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:14 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:14 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:14 volumio volumio[884]: STREAMING PROXY: Handling url /?data=qobuz://song/90384454 May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:14 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:14 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:14 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:14 volumio volumio[884]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd May 24 20:01:14 volumio volumio[884]: info: ------------------------------ 43ms May 24 20:01:14 volumio volumio[884]: info: May 24 20:01:14 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:14 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:14 volumio volumio[884]: error: updateQueue error: null May 24 20:01:14 volumio volumio[884]: info: ------------------------------ 44ms May 24 20:01:14 volumio volumio[884]: info: sendMpdCommand playlistinfo took 40 milliseconds May 24 20:01:14 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:14 volumio volumio[884]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 20:01:14 volumio volumio[884]: info: ------------------------------ 58ms May 24 20:01:14 volumio volumio[884]: info: Executing endpoint getStreamUrlqobuz May 24 20:01:14 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 24 20:01:15 volumio volumio[884]: info: getStreamUrl took 398 milliseconds May 24 20:01:15 volumio volumio[884]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=355745&eid=90384454&fmt=7&profile=raw&app_id=539451548&cid=2066930&etsp=1748113275&hmac=KDMeh_s812CfvbT23rk4ZXyr0cs May 24 20:01:15 volumio volumio[884]: STREAMING PROXY: Response: 200, length: 29502529 May 24 20:01:15 volumio volumio[884]: error: updateQueue error: null May 24 20:01:15 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/90384454" May 24 20:01:15 volumio volumio[884]: info: ------------------------------ 873ms May 24 20:01:15 volumio volumio[884]: STREAMING PROXY: Client dropped request, destroying May 24 20:01:15 volumio volumio[884]: info: May 24 20:01:15 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:15 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:15 volumio volumio[884]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/90384454" took 4 milliseconds May 24 20:01:15 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 20:01:15 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand play May 24 20:01:15 volumio volumio[884]: info: May 24 20:01:15 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:15 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:15 volumio volumio[884]: STREAMING PROXY: Handling url /?data=qobuz://song/90384454 May 24 20:01:15 volumio volumio[884]: info: ------------------------------ 10ms May 24 20:01:15 volumio volumio[884]: info: sendMpdCommand play took 8 milliseconds May 24 20:01:15 volumio volumio[884]: info: ------------------------------ 7ms May 24 20:01:15 volumio volumio[884]: info: Executing endpoint getStreamUrlqobuz May 24 20:01:15 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 24 20:01:16 volumio volumio[884]: info: getStreamUrl took 392 milliseconds May 24 20:01:16 volumio volumio[884]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=355745&eid=90384454&fmt=7&profile=raw&app_id=539451548&cid=2066930&etsp=1748113275&hmac=KDMeh_s812CfvbT23rk4ZXyr0cs May 24 20:01:16 volumio volumio[884]: STREAMING PROXY: Response: 200, length: 29502529 May 24 20:01:16 volumio volumio[884]: info: May 24 20:01:16 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:16 volumio volumio[884]: info: May 24 20:01:16 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:16 volumio volumio[884]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 24 20:01:16 volumio volumio[884]: info: FusionDsp - ---- read samplerate from file: 44100 May 24 20:01:16 volumio volumio[884]: info: camilladsp stopping service pid 4898... May 24 20:01:16 volumio volumio[884]: info: camilladsp service terminated, instance 1 May 24 20:01:16 volumio volumio[884]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 24 20:01:16 volumio volumio[884]: info: camilladsp service started and running in background, instance 1 May 24 20:01:16 volumio volumio[884]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 24 20:01:16 volumio volumio[884]: info: FusionDsp - ---- read samplerate from file: 44100 May 24 20:01:16 volumio volumio[884]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 24 20:01:16 volumio volumio[884]: info: May 24 20:01:16 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:16 volumio volumio[884]: info: sendMpdCommand status took 173 milliseconds May 24 20:01:16 volumio volumio[884]: info: sendMpdCommand status took 170 milliseconds May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:16 volumio volumio[884]: info: May 24 20:01:16 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:16 volumio volumio[884]: info: sendMpdCommand status took 25 milliseconds May 24 20:01:16 volumio volumio[884]: info: sendMpdCommand playlistinfo took 23 milliseconds May 24 20:01:16 volumio volumio[884]: info: sendMpdCommand status took 15 milliseconds May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:16 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:16 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"90384454","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/90384454","trackType":"qobuz"} May 24 20:01:16 volumio volumio[884]: verbose: CURRENT POSITION 0 May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus stop May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:16 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:16 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"90384454","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/90384454","trackType":"qobuz"} May 24 20:01:16 volumio volumio[884]: verbose: CURRENT POSITION 0 May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:16 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:16 volumio volumio[884]: info: ------------------------------ 279ms May 24 20:01:16 volumio volumio[884]: info: ------------------------------ 309ms May 24 20:01:16 volumio volumio[884]: info: sendMpdCommand playlistinfo took 163 milliseconds May 24 20:01:16 volumio volumio[884]: info: sendMpdCommand playlistinfo took 159 milliseconds May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:16 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:16 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:16 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":149,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1656 Kbps","isStreaming":false,"title":"90384454","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/90384454","trackType":"qobuz"} May 24 20:01:16 volumio volumio[884]: verbose: CURRENT POSITION 0 May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:16 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:16 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:16 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:16 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":149,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1545 Kbps","isStreaming":false,"title":"90384454","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/90384454","trackType":"qobuz"} May 24 20:01:16 volumio volumio[884]: verbose: CURRENT POSITION 0 May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:16 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:16 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:16 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:16 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:16 volumio volumio[884]: info: ------------------------------ 362ms May 24 20:01:16 volumio volumio[884]: info: ------------------------------ 352ms May 24 20:01:16 volumio volumio[884]: error: FusionDsp - WebSocket error: [object Object] May 24 20:01:21 volumio volumio[884]: STREAMING PROXY: Client dropped request, destroying May 24 20:01:21 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 20:01:21 volumio volumio[884]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 24 20:01:21 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 24 20:01:21 volumio volumio[884]: info: Received Get System Version May 24 20:01:21 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 20:01:21 volumio volumio[884]: info: Received Get System Info May 24 20:01:21 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 20:01:21 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 20:01:21 volumio volumio[884]: info: Discovery: Getting this device information May 24 20:01:21 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:21 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 20:01:23 volumio volumio[884]: Searching all installed plugins May 24 20:01:23 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 20:01:23 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: , search May 24 20:01:23 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 24 20:01:23 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 24 20:01:23 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 24 20:01:23 volumio volumio[884]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 24 20:01:23 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 24 20:01:23 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 24 20:01:25 volumio volumio[884]: info: searchQOBUZUri took 1524 milliseconds May 24 20:01:25 volumio volumio[884]: info: All search sources collected, pushing search results May 24 20:01:40 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 24 20:01:41 volumio volumio[884]: info: handleBrowseUri took 1536 milliseconds May 24 20:01:41 volumio volumio[884]: info: Preload queue cleared May 24 20:01:41 volumio volumio[884]: info: Executing endpoint getSimilarArtists May 24 20:01:41 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists May 24 20:01:41 volumio volumio[884]: info: Executing endpoint metavolumio May 24 20:01:41 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 20:01:44 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 24 20:01:44 volumio volumio[884]: info: handleBrowseUri took 387 milliseconds May 24 20:01:44 volumio volumio[884]: info: Preload queue cleared May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422394 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422395 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422396 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422397 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422398 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422399 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422400 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422401 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422402 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422403 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422404 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422405 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422406 May 24 20:01:44 volumio volumio[884]: info: Preloading song: qobuz://song/46422407 May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422394 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422395 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422396 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422397 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Executing endpoint getSimilarAlbums May 24 20:01:45 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422398 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422399 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Executing endpoint metavolumio May 24 20:01:45 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 20:01:45 volumio volumio[884]: info: Executing endpoint metavolumio May 24 20:01:45 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422400 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422401 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422402 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422403 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422404 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: explodeUri took 517 milliseconds May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422405 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: explodeUri took 582 milliseconds May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422406 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: explodeUri took 546 milliseconds May 24 20:01:45 volumio volumio[884]: info: explodeUri took 515 milliseconds May 24 20:01:45 volumio volumio[884]: info: Exploding uri qobuz://song/46422407 in service qobuz May 24 20:01:45 volumio volumio[884]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 24 20:01:45 volumio volumio[884]: info: explodeUri took 536 milliseconds May 24 20:01:45 volumio volumio[884]: info: explodeUri took 667 milliseconds May 24 20:01:45 volumio volumio[884]: info: explodeUri took 619 milliseconds May 24 20:01:45 volumio volumio[884]: info: explodeUri took 572 milliseconds May 24 20:01:46 volumio volumio[884]: info: explodeUri took 587 milliseconds May 24 20:01:46 volumio volumio[884]: info: explodeUri took 626 milliseconds May 24 20:01:46 volumio volumio[884]: info: explodeUri took 638 milliseconds May 24 20:01:46 volumio volumio[884]: info: explodeUri took 622 milliseconds May 24 20:01:46 volumio volumio[884]: info: explodeUri took 508 milliseconds May 24 20:01:46 volumio volumio[884]: info: explodeUri took 596 milliseconds May 24 20:01:48 volumio volumio[884]: error: Failed request for metavolumio API May 24 20:01:48 volumio volumio[884]: info: Preload queue cleared May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::ClearQueue May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::stop May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::stPlaybackTimer May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:48 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:48 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::serviceStop May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrack 0 May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::serviceStop May 24 20:01:48 volumio volumio[884]: info: [1748109708853] ControllerQobuz::stop May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 20:01:48 volumio volumio[884]: info: ControllerMpd::stop May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand stop May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::clearPlayQueue May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::addQueueItems May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::addQueueItems May 24 20:01:48 volumio volumio[884]: info: Preload queue cleared May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422394 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422394 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422395 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422395 May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioPlay May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::play index 1 May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::addQueueItems May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::addQueueItems May 24 20:01:48 volumio volumio[884]: info: Preload queue cleared May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422396 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422396 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422397 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422397 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422398 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422398 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422399 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422399 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422400 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422400 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422401 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422401 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422402 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422402 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422403 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422403 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422404 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422404 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422405 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422405 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422406 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422406 May 24 20:01:48 volumio volumio[884]: info: Adding Item to queue: qobuz://song/46422407 May 24 20:01:48 volumio volumio[884]: info: Using cached record of: qobuz://song/46422407 May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::stop May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioPushQueue May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::saveQueue May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::play index undefined May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::updateTrackBlock May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrackBlock May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::startPlaybackTimer May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:48 volumio volumio[884]: info: [1748109708925] ControllerQobuz::clearAddPlayTrack May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand stop May 24 20:01:48 volumio volumio[884]: info: May 24 20:01:48 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:48 volumio volumio[884]: info: sendMpdCommand stop took 111 milliseconds May 24 20:01:48 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:48 volumio volumio[884]: info: sendMpdCommand stop took 41 milliseconds May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand clear May 24 20:01:48 volumio volumio[884]: info: May 24 20:01:48 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:48 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:48 volumio volumio[884]: info: May 24 20:01:48 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:48 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:48 volumio volumio[884]: info: sendMpdCommand status took 12 milliseconds May 24 20:01:48 volumio volumio[884]: info: sendMpdCommand clear took 11 milliseconds May 24 20:01:48 volumio volumio[884]: info: sendMpdCommand status took 8 milliseconds May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/46422395" May 24 20:01:48 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:48 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:48 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:48 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:48 volumio volumio[884]: STREAMING PROXY: Handling url /?data=qobuz://song/46422395 May 24 20:01:48 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:49 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:49 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:49 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:49 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:49 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:49 volumio volumio[884]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd May 24 20:01:49 volumio volumio[884]: info: ------------------------------ 46ms May 24 20:01:49 volumio volumio[884]: info: May 24 20:01:49 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:49 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:49 volumio volumio[884]: error: updateQueue error: null May 24 20:01:49 volumio volumio[884]: info: ------------------------------ 47ms May 24 20:01:49 volumio volumio[884]: info: sendMpdCommand playlistinfo took 42 milliseconds May 24 20:01:49 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:49 volumio volumio[884]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 20:01:49 volumio volumio[884]: info: ------------------------------ 65ms May 24 20:01:49 volumio volumio[884]: info: Executing endpoint getStreamUrlqobuz May 24 20:01:49 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 24 20:01:49 volumio volumio[884]: info: getStreamUrl took 399 milliseconds May 24 20:01:49 volumio volumio[884]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=355745&eid=46422395&fmt=7&profile=raw&app_id=539451548&cid=2066930&etsp=1748113309&hmac=SlIPPj71neOCqR0yIoXhwNbAqEA May 24 20:01:49 volumio volumio[884]: STREAMING PROXY: Response: 200, length: 42624525 May 24 20:01:49 volumio volumio[884]: STREAMING PROXY: Client dropped request, destroying May 24 20:01:49 volumio volumio[884]: error: updateQueue error: null May 24 20:01:49 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/46422395" May 24 20:01:49 volumio volumio[884]: info: ------------------------------ 697ms May 24 20:01:49 volumio volumio[884]: info: May 24 20:01:49 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:49 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:49 volumio volumio[884]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/46422395" took 7 milliseconds May 24 20:01:49 volumio volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 20:01:49 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand play May 24 20:01:49 volumio volumio[884]: info: May 24 20:01:49 volumio volumio[884]: ---------------------------- MPD announces system playlist update May 24 20:01:49 volumio volumio[884]: info: Ignoring MPD Status Update May 24 20:01:49 volumio volumio[884]: STREAMING PROXY: Handling url /?data=qobuz://song/46422395 May 24 20:01:49 volumio volumio[884]: info: ------------------------------ 20ms May 24 20:01:49 volumio volumio[884]: info: sendMpdCommand play took 15 milliseconds May 24 20:01:49 volumio volumio[884]: info: ------------------------------ 16ms May 24 20:01:49 volumio volumio[884]: info: Executing endpoint getStreamUrlqobuz May 24 20:01:49 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 24 20:01:50 volumio volumio[884]: info: getStreamUrl took 425 milliseconds May 24 20:01:50 volumio volumio[884]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=355745&eid=46422395&fmt=7&profile=raw&app_id=539451548&cid=2066930&etsp=1748113310&hmac=Lfm0K5bGcvrkiaEoDu7aRSkvG84 May 24 20:01:50 volumio volumio[884]: STREAMING PROXY: Response: 200, length: 42624525 May 24 20:01:50 volumio volumio[884]: info: May 24 20:01:50 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:50 volumio volumio[884]: info: May 24 20:01:50 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:50 volumio volumio[884]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 24 20:01:50 volumio volumio[884]: info: FusionDsp - ---- read samplerate from file: 44100 May 24 20:01:50 volumio volumio[884]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 24 20:01:50 volumio volumio[884]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 24 20:01:50 volumio volumio[884]: info: FusionDsp - ---- read samplerate from file: 44100 May 24 20:01:50 volumio volumio[884]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 24 20:01:50 volumio volumio[884]: info: May 24 20:01:50 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand status took 58 milliseconds May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand status took 71 milliseconds May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:50 volumio volumio[884]: info: May 24 20:01:50 volumio volumio[884]: ---------------------------- MPD announces state update: player May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::getState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand status took 24 milliseconds May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand playlistinfo took 20 milliseconds May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand playlistinfo took 16 milliseconds May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand status took 12 milliseconds May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::parseState May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:50 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:50 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"46422395","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46422395","trackType":"qobuz"} May 24 20:01:50 volumio volumio[884]: verbose: CURRENT POSITION 1 May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus stop May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:50 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:50 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"46422395","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46422395","trackType":"qobuz"} May 24 20:01:50 volumio volumio[884]: verbose: CURRENT POSITION 1 May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:50 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:50 volumio volumio[884]: info: ------------------------------ 189ms May 24 20:01:50 volumio volumio[884]: info: ------------------------------ 214ms May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand playlistinfo took 116 milliseconds May 24 20:01:50 volumio volumio[884]: info: sendMpdCommand playlistinfo took 113 milliseconds May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:50 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:50 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:50 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1201 Kbps","isStreaming":false,"title":"46422395","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46422395","trackType":"qobuz"} May 24 20:01:50 volumio volumio[884]: verbose: CURRENT POSITION 1 May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:50 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:50 volumio volumio[884]: info: ControllerMpd::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::servicePushState May 24 20:01:50 volumio volumio[884]: info: CorePlayQueue::getTrack 1 May 24 20:01:50 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1408 Kbps","isStreaming":false,"title":"46422395","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46422395","trackType":"qobuz"} May 24 20:01:50 volumio volumio[884]: verbose: CURRENT POSITION 1 May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState stateService play May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play May 24 20:01:50 volumio volumio[884]: info: Received an update from plugin. extracting info from payload May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:50 volumio volumio[884]: info: CoreStateMachine::pushState May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioPushState May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output update for this device May 24 20:01:50 volumio volumio[884]: info: MRS: Pushing multiroomSync output May 24 20:01:50 volumio volumio[884]: info: CoreCommandRouter::volumioGetState May 24 20:01:50 volumio volumio[884]: info: ------------------------------ 324ms May 24 20:01:50 volumio volumio[884]: info: ------------------------------ 293ms May 24 20:01:50 volumio volumio[884]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 24 20:01:50 volumio volumio[884]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 24 20:01:59 volumio volumio[884]: STREAMING PROXY: Client dropped request, destroying May 24 20:02:11 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 24 20:02:11 volumio volumio[884]: info: Preload queue cleared May 24 20:02:13 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 24 20:02:13 volumio volumio[884]: info: Preload queue cleared May 24 20:02:14 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 24 20:02:15 volumio volumio[884]: info: Preload queue cleared May 24 20:02:16 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 24 20:02:16 volumio volumio[884]: info: Preload queue cleared May 24 20:02:18 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 24 20:02:20 volumio volumio[884]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 20:02:20 volumio volumio[884]: TypeError: Cannot read property 'length' of undefined May 24 20:02:20 volumio volumio[884]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 24 20:02:20 volumio volumio[884]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 24 20:02:20 volumio volumio[884]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 24 20:02:20 volumio volumio[884]: at Parser.emit (events.js:315:20) May 24 20:02:20 volumio volumio[884]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 24 20:02:20 volumio volumio[884]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 24 20:02:20 volumio volumio[884]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 24 20:02:20 volumio volumio[884]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 24 20:02:20 volumio volumio[884]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 24 20:02:20 volumio volumio[884]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 24 20:02:20 volumio volumio[884]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 24 20:02:20 volumio volumio[884]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 24 20:02:20 volumio volumio[884]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 24 20:02:20 volumio volumio[884]: at IncomingMessage.emit (events.js:327:22) May 24 20:02:20 volumio volumio[884]: at endReadableNT (internal/streams/readable.js:1327:12) May 24 20:02:20 volumio volumio[884]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 24 20:02:20 volumio volumio[884]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 20:02:22 volumio sudo[5089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 20:01 May 24 20:02:22 volumio sudo[5089]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"