-- Logs begin at Sun 2025-06-22 11:10:28 CEST, end at Sun 2025-06-22 16:20:34 CEST. -- Jun 22 16:19:00 volumio qobuz-connect[1646]: 20250622 16:19:00.511 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:19:00 volumio qobuz-connect[1646]: 20250622 16:19:00.511 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:19:00 volumio qobuz-connect[1646]: 20250622 16:19:00.511 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:19:00 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:19:00 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:19:00 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:19:00 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:19:06 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:07 volumio sudo[11410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 22 16:19:07 volumio sudo[11410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 16:19:07 volumio sudo[11410]: pam_unix(sudo:session): session closed for user root Jun 22 16:19:07 volumio sudo[11413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 22 16:19:07 volumio sudo[11413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 16:19:07 volumio sudo[11413]: pam_unix(sudo:session): session closed for user root Jun 22 16:19:07 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.232 UA: Mozilla/5.0 (Linux; Android 15; Pixel 8 Pro Build/BP1A.250505.005.B1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 22 16:19:07 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 22 16:19:07 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 22 16:19:07 volumio sudo[11416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 22 16:19:07 volumio sudo[11416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 16:19:07 volumio sudo[11416]: pam_unix(sudo:session): session closed for user root Jun 22 16:19:07 volumio sudo[11419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 22 16:19:07 volumio sudo[11419]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 16:19:07 volumio sudo[11419]: pam_unix(sudo:session): session closed for user root Jun 22 16:19:07 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.232 UA: Mozilla/5.0 (Linux; Android 15; Pixel 8 Pro Build/BP1A.250505.005.B1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::volumioGetVisibleSources Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::volumioGetQueue Jun 22 16:19:08 volumio volumio[1267]: info: CoreStateMachine::getQueue Jun 22 16:19:08 volumio volumio[1267]: info: CorePlayQueue::getQueue Jun 22 16:19:08 volumio volumio[1267]: info: Listing playlists Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 22 16:19:08 volumio volumio[1267]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 22 16:19:08 volumio volumio[1267]: info: Received Get System Info Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:19:08 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 22 16:19:08 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 22 16:19:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 22 16:19:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 22 16:19:09 volumio volumio[1267]: info: Received Get System Info Jun 22 16:19:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:19:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:19:09 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:19:09 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:19:10 volumio qobuz-connect[1646]: 20250622 16:19:10.511 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:19:10 volumio qobuz-connect[1646]: 20250622 16:19:10.511 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:19:10 volumio qobuz-connect[1646]: 20250622 16:19:10.511 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:19:10 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:19:10 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:19:10 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 22 16:19:10 volumio volumio[1267]: info: Received Get System Info Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:19:10 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:10 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:19:17 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:19:17 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284861 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284852 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284864 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284862 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284855 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284853 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284858 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284860 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284854 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284856 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284857 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284863 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284865 Jun 22 16:19:17 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284859 Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284861 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284852 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284864 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284862 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284855 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284853 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284858 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284860 in service upnp_browser Jun 22 16:19:17 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 22 16:19:17 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284854 in service upnp_browser Jun 22 16:19:18 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284856 in service upnp_browser Jun 22 16:19:18 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284857 in service upnp_browser Jun 22 16:19:18 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284863 in service upnp_browser Jun 22 16:19:18 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284865 in service upnp_browser Jun 22 16:19:18 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284859 in service upnp_browser Jun 22 16:19:20 volumio qobuz-connect[1646]: 20250622 16:19:20.511 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:19:20 volumio qobuz-connect[1646]: 20250622 16:19:20.511 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:19:20 volumio qobuz-connect[1646]: 20250622 16:19:20.511 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:19:20 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:20 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:19:20 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:19:20 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:19:20 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:19:20 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:19:20 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:19:20 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:19:22 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286074 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286077 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286065 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286069 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286073 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286079 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286072 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286068 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286071 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286081 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286067 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286080 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286075 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286070 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286076 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286066 Jun 22 16:19:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286078 Jun 22 16:19:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286074 in service upnp_browser Jun 22 16:19:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286077 in service upnp_browser Jun 22 16:19:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286065 in service upnp_browser Jun 22 16:19:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286069 in service upnp_browser Jun 22 16:19:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286073 in service upnp_browser Jun 22 16:19:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286079 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286072 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286068 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286071 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286081 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286067 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286080 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286075 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286070 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286076 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286066 in service upnp_browser Jun 22 16:19:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286078 in service upnp_browser Jun 22 16:19:25 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 22 16:19:25 volumio volumio[1267]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 22 16:19:25 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 22 16:19:25 volumio volumio[1267]: info: Received Get System Version Jun 22 16:19:25 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 16:19:25 volumio volumio[1267]: info: Received Get System Info Jun 22 16:19:25 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:19:25 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:19:25 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:19:25 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:25 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:19:27 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:19:27 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:19:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286639 Jun 22 16:19:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286637 Jun 22 16:19:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286638 Jun 22 16:19:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286640 Jun 22 16:19:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286641 Jun 22 16:19:27 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286639 in service upnp_browser Jun 22 16:19:27 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286637 in service upnp_browser Jun 22 16:19:27 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286638 in service upnp_browser Jun 22 16:19:27 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286640 in service upnp_browser Jun 22 16:19:27 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286641 in service upnp_browser Jun 22 16:19:30 volumio qobuz-connect[1646]: 20250622 16:19:30.510 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:19:30 volumio qobuz-connect[1646]: 20250622 16:19:30.510 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:19:30 volumio qobuz-connect[1646]: 20250622 16:19:30.511 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:19:30 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:19:30 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:19:30 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:19:30 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:19:37 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:19:37 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286210 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286207 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286202 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286211 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286203 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286206 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286208 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286204 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286205 Jun 22 16:19:37 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286209 Jun 22 16:19:37 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286210 in service upnp_browser Jun 22 16:19:37 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286207 in service upnp_browser Jun 22 16:19:37 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286202 in service upnp_browser Jun 22 16:19:37 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286211 in service upnp_browser Jun 22 16:19:37 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286203 in service upnp_browser Jun 22 16:19:37 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286206 in service upnp_browser Jun 22 16:19:38 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286208 in service upnp_browser Jun 22 16:19:38 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286204 in service upnp_browser Jun 22 16:19:38 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286205 in service upnp_browser Jun 22 16:19:38 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286209 in service upnp_browser Jun 22 16:19:40 volumio qobuz-connect[1646]: 20250622 16:19:40.511 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:19:40 volumio qobuz-connect[1646]: 20250622 16:19:40.511 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:19:40 volumio qobuz-connect[1646]: 20250622 16:19:40.511 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:19:40 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:40 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:19:40 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:19:40 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:19:40 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:19:40 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:19:40 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:19:40 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:19:41 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:19:44 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:19:44 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284786 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284779 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284785 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284784 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284782 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284780 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284781 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284788 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284783 Jun 22 16:19:44 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284787 Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284786 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284779 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284785 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284784 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284782 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284780 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284781 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284788 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284783 in service upnp_browser Jun 22 16:19:44 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284787 in service upnp_browser Jun 22 16:19:50 volumio qobuz-connect[1646]: 20250622 16:19:50.511 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:19:50 volumio qobuz-connect[1646]: 20250622 16:19:50.511 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:19:50 volumio qobuz-connect[1646]: 20250622 16:19:50.511 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:19:50 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:50 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:19:50 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:19:50 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:19:50 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:19:50 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:19:50 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:19:50 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:19:56 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:19:56 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:19:56 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286552 Jun 22 16:19:56 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286551 Jun 22 16:19:56 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286550 Jun 22 16:19:56 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286549 Jun 22 16:19:56 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286552 in service upnp_browser Jun 22 16:19:56 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286551 in service upnp_browser Jun 22 16:19:56 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286550 in service upnp_browser Jun 22 16:19:56 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286549 in service upnp_browser Jun 22 16:20:00 volumio qobuz-connect[1646]: 20250622 16:20:00.511 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:20:00 volumio qobuz-connect[1646]: 20250622 16:20:00.511 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:20:00 volumio qobuz-connect[1646]: 20250622 16:20:00.511 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:20:00 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:20:00 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:20:00 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:20:00 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:00 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:20:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:20:09 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285944 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285946 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285941 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285950 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285948 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285945 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285942 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285947 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285949 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285951 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285952 Jun 22 16:20:09 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285943 Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285944 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285946 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285941 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285950 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285948 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285945 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285942 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285947 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285949 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285951 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285952 in service upnp_browser Jun 22 16:20:09 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285943 in service upnp_browser Jun 22 16:20:10 volumio qobuz-connect[1646]: 20250622 16:20:10.512 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:20:10 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:10 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:20:10 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:20:10 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:20:10 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:20:10 volumio qobuz-connect[1646]: 20250622 16:20:10.513 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:20:10 volumio qobuz-connect[1646]: 20250622 16:20:10.513 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:20:10 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:20:10 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:20:10 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:13 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:20:13 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285768 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285771 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285772 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285779 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285769 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285776 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285775 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285766 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285774 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285778 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285773 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285780 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285767 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285777 Jun 22 16:20:13 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285770 Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285768 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285771 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285772 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285779 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285769 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285776 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285775 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285766 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285774 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285778 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285773 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285780 in service upnp_browser Jun 22 16:20:13 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285767 in service upnp_browser Jun 22 16:20:14 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285777 in service upnp_browser Jun 22 16:20:14 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@285770 in service upnp_browser Jun 22 16:20:20 volumio qobuz-connect[1646]: 20250622 16:20:20.513 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:20:20 volumio qobuz-connect[1646]: 20250622 16:20:20.513 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:20:20 volumio qobuz-connect[1646]: 20250622 16:20:20.513 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:20:20 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:20 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:20:20 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:20:20 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:20:20 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:20:20 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:20:20 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:20:20 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:20:22 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284397 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284392 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284402 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284395 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284400 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284390 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284401 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284393 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284396 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284391 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284394 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284389 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284399 Jun 22 16:20:22 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284398 Jun 22 16:20:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284397 in service upnp_browser Jun 22 16:20:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284392 in service upnp_browser Jun 22 16:20:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284402 in service upnp_browser Jun 22 16:20:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284395 in service upnp_browser Jun 22 16:20:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284400 in service upnp_browser Jun 22 16:20:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284390 in service upnp_browser Jun 22 16:20:22 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284401 in service upnp_browser Jun 22 16:20:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284393 in service upnp_browser Jun 22 16:20:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284396 in service upnp_browser Jun 22 16:20:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284391 in service upnp_browser Jun 22 16:20:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284394 in service upnp_browser Jun 22 16:20:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284389 in service upnp_browser Jun 22 16:20:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284399 in service upnp_browser Jun 22 16:20:23 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@284398 in service upnp_browser Jun 22 16:20:27 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:20:27 volumio volumio[1267]: info: Preload queue cleared Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286129 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286133 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286132 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286127 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286128 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286130 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286123 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286126 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286131 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286124 Jun 22 16:20:27 volumio volumio[1267]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286125 Jun 22 16:20:27 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286129 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286133 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286132 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286127 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286128 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286130 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286123 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286126 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286131 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286124 in service upnp_browser Jun 22 16:20:28 volumio volumio[1267]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@286125 in service upnp_browser Jun 22 16:20:30 volumio qobuz-connect[1646]: 20250622 16:20:30.513 [1646.1646] INFO ProtocolHandler: [0x821be0]: Need to send state update Jun 22 16:20:30 volumio qobuz-connect[1646]: 20250622 16:20:30.513 [1646.1646] INFO QConnectMessageSender: [0x9180b0]: Sending StateUpdated message Jun 22 16:20:30 volumio qobuz-connect[1646]: 20250622 16:20:30.513 [1646.1646] INFO QwspMessageSender: [0x824ac0]: Sending Payload message Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Jun 22 16:20:30 volumio volumio[1267]: info: CoreStateMachine::pushState Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Jun 22 16:20:30 volumio volumio[1267]: info: MRS: Pushing multiroomSync output update for this device Jun 22 16:20:30 volumio volumio[1267]: info: MRS: Pushing multiroomSync output Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 16:20:30 volumio volumio[1267]: info: Discovery: Getting this device information Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState Jun 22 16:20:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 16:20:34 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 22 16:20:34 volumio volumio[1267]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 16:20:34 volumio volumio[1267]: TypeError: Cannot read property 'length' of undefined Jun 22 16:20:34 volumio volumio[1267]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 22 16:20:34 volumio volumio[1267]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 22 16:20:34 volumio volumio[1267]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 22 16:20:34 volumio volumio[1267]: at Parser.emit (events.js:315:20) Jun 22 16:20:34 volumio volumio[1267]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 22 16:20:34 volumio volumio[1267]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 22 16:20:34 volumio volumio[1267]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 22 16:20:34 volumio volumio[1267]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 22 16:20:34 volumio volumio[1267]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 22 16:20:34 volumio volumio[1267]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 22 16:20:34 volumio volumio[1267]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 22 16:20:34 volumio volumio[1267]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 22 16:20:34 volumio volumio[1267]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 22 16:20:34 volumio volumio[1267]: at IncomingMessage.emit (events.js:327:22) Jun 22 16:20:34 volumio volumio[1267]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 22 16:20:34 volumio volumio[1267]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 22 16:20:34 volumio volumio[1267]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 16:20:34 volumio sudo[11613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-22 16:19 Jun 22 16:20:34 volumio sudo[11613]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"