-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-08-31 13:35:58 CEST. -- Aug 31 13:34:03 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:34:04 volumio1 qobuz-connect[1554]: 20250831 13:34:04.205 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:34:04 volumio1 qobuz-connect[1554]: 20250831 13:34:04.205 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:34:04 volumio1 qobuz-connect[1554]: 20250831 13:34:04.205 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:34:04 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:04 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:34:04 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:34:04 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:34:04 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:34:04 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:05 volumio1 volumio[1167]: verbose: New Socket.io Connection to 192.168.0.245:3000 from 192.168.0.12 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 31 13:34:14 volumio1 qobuz-connect[1554]: 20250831 13:34:14.205 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:34:14 volumio1 qobuz-connect[1554]: 20250831 13:34:14.205 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:34:14 volumio1 qobuz-connect[1554]: 20250831 13:34:14.205 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:34:14 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:14 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:34:14 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:34:14 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:34:14 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:34:14 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:14 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:34:24 volumio1 qobuz-connect[1554]: 20250831 13:34:24.206 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:34:24 volumio1 qobuz-connect[1554]: 20250831 13:34:24.207 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:34:24 volumio1 qobuz-connect[1554]: 20250831 13:34:24.207 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:34:24 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:24 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:34:24 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:34:24 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:34:24 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:34:24 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:25 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:34:34 volumio1 qobuz-connect[1554]: 20250831 13:34:34.207 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:34:34 volumio1 qobuz-connect[1554]: 20250831 13:34:34.207 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:34:34 volumio1 qobuz-connect[1554]: 20250831 13:34:34.207 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:34:34 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:34 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:34:34 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:34:34 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:34:34 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:34:34 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:36 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:34:44 volumio1 qobuz-connect[1554]: 20250831 13:34:44.208 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:34:44 volumio1 qobuz-connect[1554]: 20250831 13:34:44.208 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:34:44 volumio1 qobuz-connect[1554]: 20250831 13:34:44.208 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:34:44 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:44 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:34:44 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:34:44 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:34:44 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:34:44 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:47 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:34:54 volumio1 qobuz-connect[1554]: 20250831 13:34:54.208 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:34:54 volumio1 qobuz-connect[1554]: 20250831 13:34:54.208 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:34:54 volumio1 qobuz-connect[1554]: 20250831 13:34:54.208 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:34:54 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:54 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:34:54 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:34:54 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:34:54 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:34:54 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:34:58 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:35:04 volumio1 qobuz-connect[1554]: 20250831 13:35:04.209 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:35:04 volumio1 qobuz-connect[1554]: 20250831 13:35:04.209 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:35:04 volumio1 qobuz-connect[1554]: 20250831 13:35:04.210 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:35:04 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:04 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:04 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:04 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:35:04 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:04 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:09 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:35:14 volumio1 qobuz-connect[1554]: 20250831 13:35:14.209 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:35:14 volumio1 qobuz-connect[1554]: 20250831 13:35:14.210 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:35:14 volumio1 qobuz-connect[1554]: 20250831 13:35:14.210 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:35:14 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:14 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:14 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:14 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:35:14 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:14 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:20 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:35:24 volumio1 qobuz-connect[1554]: 20250831 13:35:24.211 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:35:24 volumio1 qobuz-connect[1554]: 20250831 13:35:24.211 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:35:24 volumio1 qobuz-connect[1554]: 20250831 13:35:24.211 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:35:24 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:24 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:24 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:24 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:35:24 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:24 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:28 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:35:28 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:35:28 volumio1 volumio[1167]: info: Discovery: Getting this device information Aug 31 13:35:28 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:28 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:35:28 volumio1 volumio[1167]: verbose: New Socket.io Connection to 192.168.0.245:3000 from 192.168.0.12 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 31 13:35:28 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:28 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 13:35:28 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 13:35:29 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:35:29 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:35:29 volumio1 volumio[1167]: info: Discovery: Getting this device information Aug 31 13:35:29 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:29 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:35:31 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:31 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:35:32 volumio1 sudo[6193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 13:35:32 volumio1 sudo[6193]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 13:35:32 volumio1 sudo[6193]: pam_unix(sudo:session): session closed for user root Aug 31 13:35:32 volumio1 sudo[6195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 13:35:32 volumio1 sudo[6195]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 13:35:32 volumio1 sudo[6195]: pam_unix(sudo:session): session closed for user root Aug 31 13:35:32 volumio1 volumio[1167]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.12 UA: Mozilla/5.0 (Linux; Android 13; 2201116TG Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 31 13:35:32 volumio1 sudo[6199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 13:35:32 volumio1 sudo[6199]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 13:35:32 volumio1 sudo[6199]: pam_unix(sudo:session): session closed for user root Aug 31 13:35:32 volumio1 sudo[6202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 13:35:32 volumio1 sudo[6202]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 13:35:32 volumio1 sudo[6202]: pam_unix(sudo:session): session closed for user root Aug 31 13:35:32 volumio1 volumio[1167]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.12 UA: Mozilla/5.0 (Linux; Android 13; 2201116TG Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 13:35:32 volumio1 volumio[1167]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 13:35:32 volumio1 volumio[1167]: info: Received Get System Info Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:35:32 volumio1 volumio[1167]: info: Discovery: Getting this device information Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:32 volumio1 volumio[1167]: info: Listing playlists Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 13:35:32 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 13:35:33 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 13:35:34 volumio1 qobuz-connect[1554]: 20250831 13:35:34.211 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:35:34 volumio1 qobuz-connect[1554]: 20250831 13:35:34.211 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:35:34 volumio1 qobuz-connect[1554]: 20250831 13:35:34.211 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 13:35:34 volumio1 volumio[1167]: info: Received Get System Info Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:35:34 volumio1 volumio[1167]: info: Discovery: Getting this device information Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:34 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:35:35 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 13:35:35 volumio1 volumio[1167]: info: Received Get System Info Aug 31 13:35:35 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:35:35 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:35:35 volumio1 volumio[1167]: info: Discovery: Getting this device information Aug 31 13:35:35 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:35 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.986 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: [Stream 9]: Running audio stream Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.986 [1554.1554] INFO UrlAudioSource: [0x13c1e98]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=6069504&eid=6286901&fmt=6&profile=raw&app_id=174516466&cid=2931846&etsp=1756643384&hmac=ZXPSiC1NSiQ2NBFBgOIJcrj0Dvo Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.986 [1554.1554] INFO ContentFetcher: [0x13d6a38]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=6069504&eid=6286901&fmt=6&profile=raw&app_id=174516466&cid=2931846&etsp=1756643384&hmac=ZXPSiC1NSiQ2NBFBgOIJcrj0Dvo, offset: 0 Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.989 [1554.1554] INFO SampleApp: [Stream 9]: New audio stream (starting from 0ms) Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.989 [1554.1554] INFO SampleApp: [Stream 9]: Stream metadata became available: Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.989 [1554.1554] INFO SampleApp: Title: Warm Shadow (Live from Epicerie Moderne, Lyon – Oct 28 2011) Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.989 [1554.1554] INFO SampleApp: Artist: Fink Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.989 [1554.1554] INFO SampleApp: Album: Wheels Turn Beneath My Feet (Live from Epicerie Moderne, Lyon – Oct 28 2011) Aug 31 13:35:41 volumio1 qobuz-connect[1554]: 20250831 13:35:41.989 [1554.1554] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/91/11/5021392751191_600.jpg Aug 31 13:35:41 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.070 [1554.1554] INFO UrlAudioSource: [0x13c1e98]: Content info extracted Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.071 [1554.1554] INFO UrlAudioSource: [0x13c1e98]: Size: 39374992 Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.072 [1554.6223] INFO AudioDecoder: [0x1453270]: Stream info: Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.072 [1554.6223] INFO AudioDecoder: [0x1453270]: Total samples: 18023376 Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.072 [1554.6223] INFO AudioDecoder: [0x1453270]: Sample rate: 44100 Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.072 [1554.6223] INFO AudioDecoder: [0x1453270]: Channel count: 2 Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.072 [1554.6223] INFO AudioDecoder: [0x1453270]: Bits per sample: 16 Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.072 [1554.6223] INFO AudioDecoder: [0x1453270]: Duration: 408693 Aug 31 13:35:42 volumio1 qobuz-connect[1554]: 20250831 13:35:42.072 [1554.1554] INFO UrlAudioSource: [0x13c1e98]: Audio properties have changed Aug 31 13:35:42 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:35:42 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 13:35:44 volumio1 qobuz-connect[1554]: 20250831 13:35:44.212 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:35:44 volumio1 qobuz-connect[1554]: 20250831 13:35:44.212 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:35:44 volumio1 qobuz-connect[1554]: 20250831 13:35:44.212 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:35:44 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:44 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:44 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:44 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:35:44 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:44 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 13:35:45 volumio1 volumio[1167]: info: Retrieving Cloud Streaming UI Aug 31 13:35:45 volumio1 volumio[1167]: info: Getting Tidal Cloud Configuration Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 13:35:45 volumio1 volumio[1167]: info: Getting Qobuz Cloud Configuration Aug 31 13:35:45 volumio1 volumio[1167]: info: Asking plugin for UI Config Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 13:35:45 volumio1 volumio[1167]: info: Getting Spotify Cloud Configuration Aug 31 13:35:45 volumio1 volumio[1167]: info: Asking plugin for UI Config Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 13:35:45 volumio1 volumio[1167]: info: Saving Spotify Acccount Aug 31 13:35:45 volumio1 volumio[1167]: info: Got it Aug 31 13:35:45 volumio1 volumio[1167]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 13:35:45 volumio1 volumio[1167]: info: Got it Aug 31 13:35:45 volumio1 volumio[1167]: info: Got Tidal Cloud Configuration Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 13:35:45 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 13:35:49 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 13:35:49 volumio1 qobuz-connect[1554]: 20250831 13:35:49.770 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: [Stream 8]: Audio stream has finished Aug 31 13:35:49 volumio1 qobuz-connect[1554]: 20250831 13:35:49.770 [1554.1554] INFO SampleApp: [Stream 8]: Audio stream finished Aug 31 13:35:49 volumio1 qobuz-connect[1554]: 20250831 13:35:49.770 [1554.1554] INFO SampleApp: Playback is nearly finished Aug 31 13:35:50 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 13:35:50 volumio1 volumio[1167]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 13:35:50 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 13:35:50 volumio1 volumio[1167]: info: Received Get System Version Aug 31 13:35:50 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 13:35:50 volumio1 volumio[1167]: info: Received Get System Info Aug 31 13:35:50 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:35:50 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:35:50 volumio1 volumio[1167]: info: Discovery: Getting this device information Aug 31 13:35:50 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:50 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO SampleApp: Playback has finished Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: [Stream 8]: Disposing of audio stream Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO ContentFetcher: [0x11e1a88]: Canceling download Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO SampleApp: [Stream 8]: Audio stream has been disposed of Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO StreamingEventReporter: [0x11b3060]: Reporting streaming ended event Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO HttpDownloader: [0x11b30b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Need to send state update Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending StateUpdated message Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.992 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.993 [1554.1554] INFO QConnectMessageSender: [0x11ee9d0]: Sending FileAudioQualityChanged message Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.993 [1554.1554] INFO QwspMessageSender: [0x11ee9c0]: Sending Payload message Aug 31 13:35:51 volumio1 qobuz-connect[1554]: 20250831 13:35:51.993 [1554.1554] INFO StreamingEventReporter: [0x11b3060]: Reporting streaming started event Aug 31 13:35:51 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:51 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:51 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:51 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:35:51 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:51 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::servicePushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreStateMachine::pushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 31 13:35:52 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.059 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Received SetState message: Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.059 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Playing state: Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Playback position: Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Queue version: 2.1 Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Current track: Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO ProtocolHandler: [0x11b2c48]: Next track: TID: 6286902, QID: 8, Context UUID: 6e0ba9a9-3836-4c3b-aa67-fc135ec90866 Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO MediaEngine: [0x11b2508]: Setting next track: 6286902 Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: New stream: 10 Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO HttpDownloader: [0x1454270]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=37a8e4b152631fb14a6001145b65543a&request_ts=1756640152&track_id=6286902 Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO HttpDownloader: [0x13f16f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=6286902 Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.060 [1554.1554] INFO HttpDownloader: [0x13b63b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=acab9b26d82571498b7a730320ae3911&request_ts=1756640152&track_id=6286902 Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.177 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: [Stream 10]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=6069504&eid=6286902&fmt=6&profile=raw&app_id=174516466&cid=2931846&etsp=1756643752&hmac=2bPtx_YPGdRDnI1iqwNnvzo0nco Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.227 [1554.1554] INFO HttpDownloader: [0x11b30b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.279 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: [Stream 10]: Metadata became available: Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.279 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: Title: Honesty (Live from La Cigale, Paris – Oct 25 2011) Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.279 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: Artist: Fink Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.279 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: Album: Wheels Turn Beneath My Feet (Live from La Cigale, Paris – Oct 25 2011) Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.279 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: Album art URL: https://static.qobuz.com/images/covers/91/11/5021392751191_600.jpg Aug 31 13:35:52 volumio1 qobuz-connect[1554]: 20250831 13:35:52.288 [1554.1554] INFO AudioStreamManager: [0x11b25f0]: [Stream 10]: stream information have been fetched Aug 31 13:35:53 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:35:53 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:35:53 volumio1 volumio[1167]: info: Discovery: Getting this device information Aug 31 13:35:53 volumio1 volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 31 13:35:53 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:35:53 volumio1 wpa_supplicant[962]: wlan0: Failed to initiate sched scan Aug 31 13:35:53 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 13:35:55 volumio1 volumio[1167]: info: Disabling MyMusic plugin upnp Aug 31 13:35:55 volumio1 sudo[6297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 31 13:35:55 volumio1 sudo[6297]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 13:35:55 volumio1 volumio[1167]: error: Upnp client error: Error: This socket has been ended by the other party Aug 31 13:35:55 volumio1 systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 31 13:35:57 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 13:35:58 volumio1 volumio[1167]: info: Enabling MyMusic plugin upnp Aug 31 13:35:58 volumio1 volumio[1167]: info: Enabling plugin upnp Aug 31 13:35:58 volumio1 volumio[1167]: info: Loading plugin "upnp"... Aug 31 13:35:58 volumio1 volumio[1167]: info: [1756640158644] Starting Upmpd Daemon Aug 31 13:35:58 volumio1 volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 13:35:58 volumio1 volumio[1167]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 13:35:58 volumio1 volumio[1167]: Error: listen EADDRINUSE: address already in use :::6599 Aug 31 13:35:58 volumio1 volumio[1167]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Aug 31 13:35:58 volumio1 volumio[1167]: at listenInCluster (net.js:1366:12) Aug 31 13:35:58 volumio1 volumio[1167]: at Server.listen (net.js:1452:7) Aug 31 13:35:58 volumio1 volumio[1167]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 31 13:35:58 volumio1 volumio[1167]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 31 13:35:58 volumio1 volumio[1167]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 31 13:35:58 volumio1 volumio[1167]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 31 13:35:58 volumio1 volumio[1167]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 31 13:35:58 volumio1 volumio[1167]: code: 'EADDRINUSE', Aug 31 13:35:58 volumio1 volumio[1167]: errno: -98, Aug 31 13:35:58 volumio1 volumio[1167]: syscall: 'listen', Aug 31 13:35:58 volumio1 volumio[1167]: address: '::', Aug 31 13:35:58 volumio1 volumio[1167]: port: 6599 Aug 31 13:35:58 volumio1 volumio[1167]: } Aug 31 13:35:58 volumio1 volumio[1167]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 13:35:58 volumio1 sudo[6308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 13:34 Aug 31 13:35:58 volumio1 sudo[6308]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"