-- Logs begin at Thu 2019-02-14 07:11:59 -03, end at Sat 2026-01-10 13:27:27 -03. -- Jan 10 13:26:00 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:26:00 volumio qobuz-connect[1429]: 20260110 13:26:00.905 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:26:00 volumio qobuz-connect[1429]: 20260110 13:26:00.905 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:26:00 volumio qobuz-connect[1429]: 20260110 13:26:00.905 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:26:00 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:00 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:26:00 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:26:00 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:26:00 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:26:00 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:10 volumio qobuz-connect[1429]: 20260110 13:26:10.905 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:26:10 volumio qobuz-connect[1429]: 20260110 13:26:10.905 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:26:10 volumio qobuz-connect[1429]: 20260110 13:26:10.905 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:26:10 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:10 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:26:10 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:26:10 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:26:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:26:10 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:11 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:26:20 volumio qobuz-connect[1429]: 20260110 13:26:20.905 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:26:20 volumio qobuz-connect[1429]: 20260110 13:26:20.905 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:26:20 volumio qobuz-connect[1429]: 20260110 13:26:20.905 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:26:20 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:20 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:26:20 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:26:20 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:26:20 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:26:20 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:22 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:26:30 volumio qobuz-connect[1429]: 20260110 13:26:30.905 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:26:30 volumio qobuz-connect[1429]: 20260110 13:26:30.905 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:26:30 volumio qobuz-connect[1429]: 20260110 13:26:30.906 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:26:30 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:30 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:26:30 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:26:30 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:26:30 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:26:30 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:33 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:26:40 volumio qobuz-connect[1429]: 20260110 13:26:40.905 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:26:40 volumio qobuz-connect[1429]: 20260110 13:26:40.906 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:26:40 volumio qobuz-connect[1429]: 20260110 13:26:40.906 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:26:40 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:40 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:26:40 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:26:40 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:26:40 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:26:40 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:44 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:26:50 volumio qobuz-connect[1429]: 20260110 13:26:50.906 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:26:50 volumio qobuz-connect[1429]: 20260110 13:26:50.906 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:26:50 volumio qobuz-connect[1429]: 20260110 13:26:50.906 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:26:50 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:50 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:26:50 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:26:50 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:26:50 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:26:50 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:26:55 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:27:00 volumio qobuz-connect[1429]: 20260110 13:27:00.906 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:00 volumio qobuz-connect[1429]: 20260110 13:27:00.906 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:00 volumio qobuz-connect[1429]: 20260110 13:27:00.907 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:00 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:00 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:00 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:00 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:00 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:00 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:06 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:27:10 volumio qobuz-connect[1429]: 20260110 13:27:10.907 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:10 volumio qobuz-connect[1429]: 20260110 13:27:10.907 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:10 volumio qobuz-connect[1429]: 20260110 13:27:10.907 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:10 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:10 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:10 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:10 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:10 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:17 volumio wpa_supplicant[864]: wlan0: Failed to initiate sched scan Jan 10 13:27:20 volumio qobuz-connect[1429]: 20260110 13:27:20.908 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:20 volumio qobuz-connect[1429]: 20260110 13:27:20.908 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:20 volumio qobuz-connect[1429]: 20260110 13:27:20.908 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:20 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:20 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:20 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:20 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:20 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:20 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.071 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 24]: Running audio stream Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.071 [1429.1429] INFO UrlAudioSource: [0x1d03c58]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=3877684&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768065731&hmac=GK6Pxyqchn7K9VV2BnPt5HTrctk Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.071 [1429.1429] INFO ContentFetcher: [0x1f394c8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=3877684&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768065731&hmac=GK6Pxyqchn7K9VV2BnPt5HTrctk, offset: 0 Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.071 [1429.1429] INFO SampleApp: [Stream 24]: New audio stream (starting from 0ms) Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.072 [1429.1429] INFO SampleApp: [Stream 24]: Stream metadata became available: Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.072 [1429.1429] INFO SampleApp: Title: A Love Supreme, Pt. I – Acknowledgement (Album Version) Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.072 [1429.1429] INFO SampleApp: Artist: John Coltrane Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.072 [1429.1429] INFO SampleApp: Album: A Love Supreme (Album Version) Jan 10 13:27:21 volumio qobuz-connect[1429]: 20260110 13:27:21.072 [1429.1429] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/52/94/0073145899452_600.jpg Jan 10 13:27:21 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.200 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Received SetState message: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playing state: Paused Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playback position: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Queue version: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Current track: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Next track: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO MediaEngine: [0x19d9518]: Pausing playback Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO AudioRenderer: [0x19d9738]: Pausing audio renderer Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] INFO SampleApp: Pausing playback Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.201 [1429.1429] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] ERROR AudioRenderer: [0x19d9738]: Playback error occurred Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending PlaybackError message Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] INFO SampleApp: Playback state changed: Stopped Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] INFO StreamingEventReporter: [0x19da070]: Reporting streaming ended event Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] INFO HttpDownloader: [0x19da0c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.207 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.208 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Received SetState message: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playing state: Playing Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playback position: 0 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Queue version: 1.2 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Current track: TID: 3877684, QID: 74, Context UUID: 14cf40cf-6a3e-4ac3-82e2-eedd3a90ca7b Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Next track: TID: 48097779, QID: 75, Context UUID: 14cf40cf-6a3e-4ac3-82e2-eedd3a90ca7b Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO MediaEngine: [0x19d9518]: Reseting Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO MediaEngine: [0x19d9518]: Stopping playback, clearing tracks Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO MediaEngine: [0x19d9518]: Initiating playback Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO AudioRenderer: [0x19d9738]: Reseting audio renderer Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.339 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.340 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.340 [1429.1429] INFO MediaEngine: [0x19d9518]: Setting current track: 3877684, initial offset: 0ms Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.340 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Clearing all streams Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.340 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 23]: Disposing of audio stream Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.340 [1429.1429] INFO ContentFetcher: [0x1aa22f8]: Canceling download Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:22 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.348 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 24]: Disposing of audio stream Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.349 [1429.1429] INFO ContentFetcher: [0x1f394c8]: Canceling download Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.356 [1429.1429] INFO AudioStreamManager: [0x19d9600]: New stream: 25 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.356 [1429.1429] INFO HttpDownloader: [0x1a3afd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=648bb54744b402b48a5bdb5cf6604ea4&request_ts=1768062442&track_id=3877684 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.356 [1429.1429] INFO HttpDownloader: [0x19dd030]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=3877684 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.356 [1429.1429] INFO HttpDownloader: [0x1a15f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6943d3c84114d75e9fc4c9a80c937666&request_ts=1768062442&track_id=3877684 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.356 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 25]: Running audio stream Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.358 [1429.1429] INFO SampleApp: [Stream 23]: Audio stream has been disposed of Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.358 [1429.1429] INFO MediaEngine: [0x19d9518]: Setting next track: 48097779 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.358 [1429.1429] INFO AudioStreamManager: [0x19d9600]: New stream: 26 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.358 [1429.1429] INFO HttpDownloader: [0x1cda1d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d2b47dff717fcf7948ab9512e0b7d25c&request_ts=1768062442&track_id=48097779 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.358 [1429.1429] INFO HttpDownloader: [0x1ac9b58]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=48097779 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.358 [1429.1429] INFO HttpDownloader: [0x1d09728]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6a94076545c8f96afbeaf100b124da0c&request_ts=1768062442&track_id=48097779 Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.360 [1429.1429] INFO SampleApp: [Stream 24]: Audio stream has been disposed of Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.360 [1429.1429] INFO MediaEngine: [0x19d9518]: Waiting for current stream to start before starting audio renderer Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.635 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 25]: Metadata became available: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.635 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Title: A Love Supreme, Pt. I – Acknowledgement (Album Version) Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.635 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Artist: John Coltrane Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.635 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album: A Love Supreme (Album Version) Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.635 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album art URL: https://static.qobuz.com/images/covers/52/94/0073145899452_600.jpg Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.671 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 25]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=3877684&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066042&hmac=a07VwO4zWjE8SJJ5oGgy0dG4nsA Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.679 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 26]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=48097779&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066042&hmac=J50sA8beUwhzQ8wGYGmAmCROOhM Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.709 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 26]: Metadata became available: Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.710 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Title: So What (Studio Sequence 1) Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.710 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Artist: Miles Davis Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.710 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album: Kind Of Blue (Legacy Edition) (Studio Sequence 1) Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.710 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album art URL: https://static.qobuz.com/images/covers/sc/wb/pmm9oz4jkwbsc_600.jpg Jan 10 13:27:22 volumio qobuz-connect[1429]: 20260110 13:27:22.729 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 26]: stream information have been fetched Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.144 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 25]: stream information have been fetched Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.144 [1429.1429] INFO UrlAudioSource: [0x1b0da28]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=3877684&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066042&hmac=a07VwO4zWjE8SJJ5oGgy0dG4nsA Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.144 [1429.1429] INFO ContentFetcher: [0x1b631f8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=3877684&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066042&hmac=a07VwO4zWjE8SJJ5oGgy0dG4nsA, offset: 0 Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.144 [1429.1429] INFO AudioRenderer: [0x19d9738]: Starting audio renderer, initial playback state: Playing Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.145 [1429.1429] INFO SampleApp: [Stream 25]: New audio stream (starting from 0ms) Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.145 [1429.1429] INFO SampleApp: [Stream 25]: Stream metadata became available: Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.145 [1429.1429] INFO SampleApp: Title: A Love Supreme, Pt. I – Acknowledgement (Album Version) Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.145 [1429.1429] INFO SampleApp: Artist: John Coltrane Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.145 [1429.1429] INFO SampleApp: Album: A Love Supreme (Album Version) Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.145 [1429.1429] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/52/94/0073145899452_600.jpg Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: QobuzConnect: alread currrent service, send playback confirmation Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.147 [1429.1429] INFO SampleApp: Initiating playback, initial state: Playing Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.230 [1429.1429] INFO SampleApp: Playback state changed: Playing Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.230 [1429.1429] INFO StreamingEventReporter: [0x19da070]: Reporting streaming started event Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.230 [1429.1429] INFO HttpDownloader: [0x19da0c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.231 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.231 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:23 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:23 volumio volumio[1027]: info: [LastFM] Current track has sufficient metadata: title (A Love Supreme, Pt. I – Acknowledgement (Album Version)) and artist (John Coltrane) passed on explicitly Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.253 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.253 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending FileAudioQualityChanged message Jan 10 13:27:23 volumio qobuz-connect[1429]: 20260110 13:27:23.254 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.1429] INFO UrlAudioSource: [0x1b0da28]: Content info extracted Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.1429] INFO UrlAudioSource: [0x1b0da28]: Size: 53750492 Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.9355] INFO AudioDecoder: [0x1f20ae8]: Stream info: Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.9355] INFO AudioDecoder: [0x1f20ae8]: Total samples: 20412420 Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.9355] INFO AudioDecoder: [0x1f20ae8]: Sample rate: 44100 Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.9355] INFO AudioDecoder: [0x1f20ae8]: Channel count: 2 Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.9355] INFO AudioDecoder: [0x1f20ae8]: Bits per sample: 16 Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.9355] INFO AudioDecoder: [0x1f20ae8]: Duration: 462866 Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.287 [1429.1429] INFO UrlAudioSource: [0x1b0da28]: Audio properties have changed Jan 10 13:27:24 volumio volumio[1027]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"A Love Supreme, Pt. I – Acknowledgement (Album Version)","url":"https://www.last.fm/music/John+Coltrane/_/A+Love+Supreme,+Pt.+I+%E2%80%93+Acknowledgement+(Album+Version)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"1587","playcount":"6099","artist":{"name":"John Coltrane","url":"https://www.last.fm/music/John+Coltrane"},"userplaycount":"0","userloved":"0","toptags":""}} Jan 10 13:27:24 volumio qobuz-connect[1429]: 20260110 13:27:24.571 [1429.1429] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Received SetState message: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playing state: Paused Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playback position: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Queue version: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Current track: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Next track: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO MediaEngine: [0x19d9518]: Pausing playback Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.009 [1429.1429] INFO AudioRenderer: [0x19d9738]: Pausing audio renderer Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.010 [1429.1429] INFO SampleApp: Pausing playback Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.010 [1429.1429] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.015 [1429.1429] ERROR AudioRenderer: [0x19d9738]: Playback error occurred Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.015 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending PlaybackError message Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.015 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.016 [1429.1429] INFO SampleApp: Playback state changed: Stopped Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.016 [1429.1429] INFO StreamingEventReporter: [0x19da070]: Reporting streaming ended event Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.016 [1429.1429] INFO HttpDownloader: [0x19da0c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.016 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.016 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.017 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Received SetState message: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playing state: Playing Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Playback position: 0 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Queue version: 1.2 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Current track: TID: 48097779, QID: 75, Context UUID: 14cf40cf-6a3e-4ac3-82e2-eedd3a90ca7b Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Next track: TID: 1002887, QID: 76, Context UUID: 14cf40cf-6a3e-4ac3-82e2-eedd3a90ca7b Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO MediaEngine: [0x19d9518]: Reseting Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO MediaEngine: [0x19d9518]: Stopping playback, clearing tracks Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO MediaEngine: [0x19d9518]: Initiating playback Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO AudioRenderer: [0x19d9738]: Reseting audio renderer Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO MediaEngine: [0x19d9518]: Setting current track: 48097779, initial offset: 0ms Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Clearing all streams Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 25]: Disposing of audio stream Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.148 [1429.1429] INFO ContentFetcher: [0x1b631f8]: Canceling download Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.157 [1429.1429] INFO AudioStreamManager: [0x19d9600]: New stream: 27 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.157 [1429.1429] INFO HttpDownloader: [0x1a0d1a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=1dea04c772d9c756e5e907fc2e56de88&request_ts=1768062446&track_id=48097779 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.158 [1429.1429] INFO HttpDownloader: [0x1ab3188]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=48097779 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.158 [1429.1429] INFO HttpDownloader: [0x19e1788]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=5b2f9ea90793906288099dc6f87a542f&request_ts=1768062446&track_id=48097779 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.158 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 27]: Running audio stream Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.160 [1429.1429] INFO SampleApp: [Stream 25]: Audio stream has been disposed of Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.160 [1429.1429] INFO MediaEngine: [0x19d9518]: Setting next track: 1002887 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.160 [1429.1429] INFO AudioStreamManager: [0x19d9600]: New stream: 28 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.160 [1429.1429] INFO HttpDownloader: [0x1f6c900]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4ac075afa8a69d8c20cc4f0019d21068&request_ts=1768062446&track_id=1002887 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.160 [1429.1429] INFO HttpDownloader: [0x1accf20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=1002887 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.160 [1429.1429] INFO HttpDownloader: [0x1c51618]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=50826123483744eb977a70a0c38d43e0&request_ts=1768062446&track_id=1002887 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.162 [1429.1429] INFO MediaEngine: [0x19d9518]: Waiting for current stream to start before starting audio renderer Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.447 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 27]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=48097779&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066046&hmac=9zxxoN81GODHk4JUVrddPaRyDf8 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.496 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 28]: stream information have been fetched Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.508 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 28]: Metadata became available: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.508 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Title: 'Round Midnight Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.508 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Artist: Thelonious Monk Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.508 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album: Genius Of Modern Music, Vol. 1 (Expanded Edition) Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.508 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album art URL: https://static.qobuz.com/images/covers/55/38/0724353213855_600.jpg Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.511 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 27]: stream information have been fetched Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.511 [1429.1429] INFO UrlAudioSource: [0x1b8c1b8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=48097779&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066046&hmac=9zxxoN81GODHk4JUVrddPaRyDf8 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.511 [1429.1429] INFO ContentFetcher: [0x1c514e8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=48097779&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066046&hmac=9zxxoN81GODHk4JUVrddPaRyDf8, offset: 0 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.511 [1429.1429] INFO AudioRenderer: [0x19d9738]: Starting audio renderer, initial playback state: Playing Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.511 [1429.1429] INFO SampleApp: [Stream 27]: New audio stream (starting from 0ms) Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: QobuzConnect: alread currrent service, send playback confirmation Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.513 [1429.1429] INFO SampleApp: Initiating playback, initial state: Playing Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.596 [1429.1429] INFO SampleApp: Playback state changed: Playing Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.596 [1429.1429] INFO StreamingEventReporter: [0x19da070]: Reporting streaming started event Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.596 [1429.1429] INFO HttpDownloader: [0x19da0c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.597 [1429.1429] INFO ProtocolHandler: [0x19d9c58]: Need to send state update Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.597 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending StateUpdated message Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreStateMachine::pushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 10 13:27:26 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.619 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.619 [1429.1429] INFO QConnectMessageSender: [0x1ab5500]: Sending FileAudioQualityChanged message Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.620 [1429.1429] INFO QwspMessageSender: [0x1b25ad0]: Sending Payload message Jan 10 13:27:26 volumio volumio[1027]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jan 10 13:27:26 volumio volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 13:27:26 volumio volumio[1027]: TypeError: Cannot read property 'indexOf' of undefined Jan 10 13:27:26 volumio volumio[1027]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jan 10 13:27:26 volumio volumio[1027]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jan 10 13:27:26 volumio volumio[1027]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jan 10 13:27:26 volumio volumio[1027]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jan 10 13:27:26 volumio volumio[1027]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jan 10 13:27:26 volumio volumio[1027]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jan 10 13:27:26 volumio volumio[1027]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jan 10 13:27:26 volumio volumio[1027]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jan 10 13:27:26 volumio volumio[1027]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jan 10 13:27:26 volumio volumio[1027]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jan 10 13:27:26 volumio volumio[1027]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jan 10 13:27:26 volumio volumio[1027]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jan 10 13:27:26 volumio volumio[1027]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jan 10 13:27:26 volumio volumio[1027]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jan 10 13:27:26 volumio volumio[1027]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jan 10 13:27:26 volumio volumio[1027]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jan 10 13:27:26 volumio volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.650 [1429.1429] INFO UrlAudioSource: [0x1b8c1b8]: Content info extracted Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.650 [1429.1429] INFO UrlAudioSource: [0x1b8c1b8]: Size: 8057214 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.652 [1429.9356] INFO AudioDecoder: [0x1a76fc8]: Stream info: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.652 [1429.9356] INFO AudioDecoder: [0x1a76fc8]: Total samples: 5032104 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.652 [1429.9356] INFO AudioDecoder: [0x1a76fc8]: Sample rate: 44100 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.652 [1429.9356] INFO AudioDecoder: [0x1a76fc8]: Channel count: 2 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.652 [1429.9356] INFO AudioDecoder: [0x1a76fc8]: Bits per sample: 16 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.652 [1429.9356] INFO AudioDecoder: [0x1a76fc8]: Duration: 114106 Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.654 [1429.1429] INFO UrlAudioSource: [0x1b8c1b8]: Audio properties have changed Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.855 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 27]: Metadata became available: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.855 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Title: So What (Studio Sequence 1) Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.855 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Artist: Miles Davis Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.855 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album: Kind Of Blue (Legacy Edition) (Studio Sequence 1) Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.856 [1429.1429] INFO AudioStreamManager: [0x19d9600]: Album art URL: https://static.qobuz.com/images/covers/sc/wb/pmm9oz4jkwbsc_600.jpg Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.856 [1429.1429] INFO SampleApp: [Stream 27]: Stream metadata became available: Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.856 [1429.1429] INFO SampleApp: Title: So What (Studio Sequence 1) Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.856 [1429.1429] INFO SampleApp: Artist: Miles Davis Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.856 [1429.1429] INFO SampleApp: Album: Kind Of Blue (Legacy Edition) (Studio Sequence 1) Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.856 [1429.1429] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/sc/wb/pmm9oz4jkwbsc_600.jpg Jan 10 13:27:26 volumio qobuz-connect[1429]: 20260110 13:27:26.938 [1429.1429] INFO AudioStreamManager: [0x19d9600]: [Stream 28]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2304271&eid=1002887&fmt=6&profile=raw&app_id=174516466&cid=1597831&etsp=1768066046&hmac=RwiNnlleaJvFEnNMPzBttzacqEI Jan 10 13:27:27 volumio sudo[9366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 13:26 Jan 10 13:27:27 volumio sudo[9366]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"