-- Logs begin at Sun 2025-12-21 10:45:24 CET, end at Tue 2025-12-23 20:20:58 CET. -- Dec 23 20:19:05 volumio qobuz-connect[1346]: 20251223 20:19:05.815 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:19:05 volumio qobuz-connect[1346]: 20251223 20:19:05.815 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:19:05 volumio qobuz-connect[1346]: 20251223 20:19:05.815 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:19:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:05 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:19:05 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:19:05 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:19:05 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:19:05 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:19:05 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:19:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:07 volumio volumio[846]: info: Listing playlists Dec 23 20:19:07 volumio volumio[846]: info: Listing playlists Dec 23 20:19:15 volumio qobuz-connect[1346]: 20251223 20:19:15.815 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:19:15 volumio qobuz-connect[1346]: 20251223 20:19:15.815 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:19:15 volumio qobuz-connect[1346]: 20251223 20:19:15.815 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:19:15 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:15 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:19:15 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:19:15 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:19:15 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:19:15 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:19:15 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:19:15 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:17 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:25 volumio qobuz-connect[1346]: 20251223 20:19:25.815 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:19:25 volumio qobuz-connect[1346]: 20251223 20:19:25.815 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:19:25 volumio qobuz-connect[1346]: 20251223 20:19:25.815 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:19:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:25 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:19:25 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:19:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:19:25 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:19:25 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:19:25 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:19:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:27 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:27 volumio volumio[846]: info: Listing playlists Dec 23 20:19:27 volumio volumio[846]: info: Listing playlists Dec 23 20:19:35 volumio qobuz-connect[1346]: 20251223 20:19:35.815 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:19:35 volumio qobuz-connect[1346]: 20251223 20:19:35.815 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:19:35 volumio qobuz-connect[1346]: 20251223 20:19:35.815 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:19:35 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:35 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:19:35 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:19:35 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:19:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:19:35 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:19:35 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:19:35 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:37 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:45 volumio qobuz-connect[1346]: 20251223 20:19:45.816 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:19:45 volumio qobuz-connect[1346]: 20251223 20:19:45.816 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:19:45 volumio qobuz-connect[1346]: 20251223 20:19:45.816 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:19:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:45 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:19:45 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:19:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:19:45 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:19:45 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:19:45 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:19:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:47 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:47 volumio volumio[846]: info: Listing playlists Dec 23 20:19:47 volumio volumio[846]: info: Listing playlists Dec 23 20:19:55 volumio qobuz-connect[1346]: 20251223 20:19:55.816 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:19:55 volumio qobuz-connect[1346]: 20251223 20:19:55.817 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:19:55 volumio qobuz-connect[1346]: 20251223 20:19:55.817 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:19:55 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:55 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:19:55 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:19:55 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:19:55 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:19:55 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:19:55 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:19:55 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:19:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:05 volumio qobuz-connect[1346]: 20251223 20:20:05.816 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:05 volumio qobuz-connect[1346]: 20251223 20:20:05.816 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:05 volumio qobuz-connect[1346]: 20251223 20:20:05.816 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:05 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:05 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:05 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:05 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:05 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:05 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:07 volumio volumio[846]: info: Listing playlists Dec 23 20:20:07 volumio volumio[846]: info: Listing playlists Dec 23 20:20:15 volumio qobuz-connect[1346]: 20251223 20:20:15.816 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:15 volumio qobuz-connect[1346]: 20251223 20:20:15.816 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:15 volumio qobuz-connect[1346]: 20251223 20:20:15.816 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:15 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:15 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:15 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:15 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:15 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:15 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:15 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:15 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:17 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:25 volumio qobuz-connect[1346]: 20251223 20:20:25.816 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:25 volumio qobuz-connect[1346]: 20251223 20:20:25.816 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:25 volumio qobuz-connect[1346]: 20251223 20:20:25.816 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:25 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:25 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:25 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:25 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:25 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:27 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:27 volumio volumio[846]: info: Listing playlists Dec 23 20:20:27 volumio volumio[846]: info: Listing playlists Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Received SetState message: Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Playing state: Playing Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Playback position: 0 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Queue version: 2.1 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Current track: TID: 365612878, QID: 48, Context UUID: e23ed444-8a44-40ac-af72-8de7dee12a23 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Next track: TID: 363413868, QID: 49, Context UUID: e23ed444-8a44-40ac-af72-8de7dee12a23 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO MediaEngine: [0x132c648]: Stopping playback, clearing tracks Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO MediaEngine: [0x132c648]: Initiating playback Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO AudioRenderer: [0x132c868]: Stopping audio renderer Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.461 [1346.1346] INFO SampleApp: Stopping playback Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.462 [1346.1346] INFO AudioStreamManager: [0x132c730]: Clearing all streams Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.462 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 1]: Disposing of audio stream Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.462 [1346.1346] INFO ContentFetcher: [0x140d210]: Canceling download Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO SampleApp: Playback state changed: Stopped Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO StreamingEventReporter: [0x132d1a0]: Reporting streaming ended event Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO HttpDownloader: [0x132d1f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO MediaEngine: [0x132c648]: Setting current track: 365612878, initial offset: 0ms Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO AudioStreamManager: [0x132c730]: Clearing all streams Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.470 [1346.1346] INFO AudioStreamManager: [0x132c730]: New stream: 3 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.471 [1346.1346] INFO HttpDownloader: [0x18ef140]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=83ae567ad0bdae0c8ada59068c36a90e&request_ts=1766517630&track_id=365612878 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.471 [1346.1346] INFO HttpDownloader: [0x1412a98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=365612878 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.471 [1346.1346] INFO HttpDownloader: [0x1343d58]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ff9e8909f205fa100000af3b3902a493&request_ts=1766517630&track_id=365612878 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.471 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 3]: Running audio stream Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.471 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:30 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:30 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:30 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:30 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:30 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:30 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:30 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.517 [1346.1346] INFO MediaEngine: [0x132c648]: Setting next track: 363413868 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.517 [1346.1346] INFO AudioStreamManager: [0x132c730]: New stream: 4 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.517 [1346.1346] INFO HttpDownloader: [0x13e0d10]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8d51b5352ad171282755b29575feaefe&request_ts=1766517630&track_id=363413868 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.517 [1346.1346] INFO HttpDownloader: [0x13ec320]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=363413868 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.517 [1346.1346] INFO HttpDownloader: [0x13f8130]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=c156568e94fdcd9716800c8303d917e5&request_ts=1766517630&track_id=363413868 Dec 23 20:20:30 volumio qobuz-connect[1346]: 20251223 20:20:30.519 [1346.1346] INFO MediaEngine: [0x132c648]: Waiting for current stream to start before starting audio renderer Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.042 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 4]: Metadata became available: Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.042 [1346.1346] INFO AudioStreamManager: [0x132c730]: Title: mistletoe and holly Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.042 [1346.1346] INFO AudioStreamManager: [0x132c730]: Artist: aron! Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.042 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album: a cozy christmas Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.042 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album art URL: https://static.qobuz.com/images/covers/ga/b6/da3mzntevb6ga_600.jpg Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.087 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=363413868&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521231&hmac=Tnk7ULk3xtu_W3YlVhRd5MeaBy8 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.091 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 3]: Metadata became available: Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.091 [1346.1346] INFO AudioStreamManager: [0x132c730]: Title: Santa Claus Is Comin' To Town Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.091 [1346.1346] INFO AudioStreamManager: [0x132c730]: Artist: Laufey Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.091 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album: A Very Laufey Holiday Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.091 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album art URL: https://static.qobuz.com/images/covers/ta/ko/nxp6cnsgjkota_600.jpg Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.113 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 3]: stream information have been fetched Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.123 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=365612878&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521231&hmac=_L8_Ra5M9sHQQU4yti_eXKC7xuQ Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.123 [1346.1346] INFO UrlAudioSource: [0x17cef48]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=365612878&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521231&hmac=_L8_Ra5M9sHQQU4yti_eXKC7xuQ Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.123 [1346.1346] INFO ContentFetcher: [0x1632a38]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=365612878&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521231&hmac=_L8_Ra5M9sHQQU4yti_eXKC7xuQ, offset: 0 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.123 [1346.1346] INFO AudioRenderer: [0x132c868]: Starting audio renderer, initial playback state: Playing Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.123 [1346.1346] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.124 [1346.1346] INFO SampleApp: [Stream 3]: Stream metadata became available: Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.124 [1346.1346] INFO SampleApp: Title: Santa Claus Is Comin' To Town Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.124 [1346.1346] INFO SampleApp: Artist: Laufey Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.124 [1346.1346] INFO SampleApp: Album: A Very Laufey Holiday Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.124 [1346.1346] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ta/ko/nxp6cnsgjkota_600.jpg Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:31 volumio volumio[846]: info: QobuzConnect: alread currrent service, send playback confirmation Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.126 [1346.1346] INFO SampleApp: Initiating playback, initial state: Playing Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.131 [1346.1346] INFO SampleApp: Playback state changed: Playing Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.131 [1346.1346] INFO StreamingEventReporter: [0x132d1a0]: Reporting streaming started event Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.131 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.131 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.131 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.131 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending FileAudioQualityChanged message Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:31 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.132 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.132 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending DeviceAudioQualityChanged message Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.132 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:31 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:31 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.159 [1346.1346] INFO HttpDownloader: [0x132d1f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.160 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 4]: stream information have been fetched Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:31 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:31 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:31 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:31 volumio volumio[846]: info: [LastFM] Current track has sufficient metadata: title (Santa Claus Is Comin' To Town) and artist (Laufey) passed on explicitly Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.299 [1346.1346] INFO UrlAudioSource: [0x17cef48]: Content info extracted Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.299 [1346.1346] INFO UrlAudioSource: [0x17cef48]: Size: 33933998 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.300 [1346.21807] INFO AudioDecoder: [0x1975ff0]: Stream info: Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.300 [1346.21807] INFO AudioDecoder: [0x1975ff0]: Total samples: 7641600 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.301 [1346.21807] INFO AudioDecoder: [0x1975ff0]: Sample rate: 48000 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.301 [1346.21807] INFO AudioDecoder: [0x1975ff0]: Channel count: 2 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.301 [1346.21807] INFO AudioDecoder: [0x1975ff0]: Bits per sample: 24 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.301 [1346.21807] INFO AudioDecoder: [0x1975ff0]: Duration: 159200 Dec 23 20:20:31 volumio qobuz-connect[1346]: 20251223 20:20:31.301 [1346.1346] INFO UrlAudioSource: [0x17cef48]: Audio properties have changed Dec 23 20:20:31 volumio volumio[846]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Santa Claus Is Comin' To Town","mbid":"3e171f42-eea2-49f2-abf2-95b75db4a2fa","url":"https://www.last.fm/music/Laufey/_/Santa+Claus+Is+Comin%27+To+Town","duration":"159000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"50435","playcount":"166484","artist":{"name":"Laufey","mbid":"21a89505-10a9-4ac0-8388-833bc07b9f86","url":"https://www.last.fm/music/Laufey"},"album":{"artist":"Laufey","title":"A Very Laufey Holiday: The Santa Claus Is Comin’ To Town Edition","url":"https://www.last.fm/music/Laufey/A+Very+Laufey+Holiday:+The+Santa+Claus+Is+Comin%E2%80%99+To+Town+Edition","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/9ea32f2c2dbbb6ff6c1a65b2690400f3.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/9ea32f2c2dbbb6ff6c1a65b2690400f3.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/9ea32f2c2dbbb6ff6c1a65b2690400f3.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/9ea32f2c2dbbb6ff6c1a65b2690400f3.png","@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":""}} Dec 23 20:20:37 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:41 volumio qobuz-connect[1346]: 20251223 20:20:41.131 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:41 volumio qobuz-connect[1346]: 20251223 20:20:41.131 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:41 volumio qobuz-connect[1346]: 20251223 20:20:41.131 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:41 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:41 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:41 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:41 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:41 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:41 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:41 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:41 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:47 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:47 volumio volumio[846]: info: Listing playlists Dec 23 20:20:47 volumio volumio[846]: info: Listing playlists Dec 23 20:20:51 volumio qobuz-connect[1346]: 20251223 20:20:51.130 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:51 volumio qobuz-connect[1346]: 20251223 20:20:51.130 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:51 volumio qobuz-connect[1346]: 20251223 20:20:51.131 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:51 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:51 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:51 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:51 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:51 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:51 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:51 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:51 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Received SetState message: Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Playing state: Playing Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Playback position: 0 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Queue version: 3.1 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Current track: TID: 337591033, QID: 48, Context UUID: 9496974b-1977-44f7-8412-571b0d296fe1 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Next track: TID: 337591034, QID: 49, Context UUID: 9496974b-1977-44f7-8412-571b0d296fe1 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO MediaEngine: [0x132c648]: Stopping playback, clearing tracks Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO MediaEngine: [0x132c648]: Initiating playback Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO AudioRenderer: [0x132c868]: Stopping audio renderer Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.651 [1346.1346] INFO SampleApp: Stopping playback Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.652 [1346.1346] INFO AudioStreamManager: [0x132c730]: Clearing all streams Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.652 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 3]: Disposing of audio stream Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.652 [1346.1346] INFO ContentFetcher: [0x1632a38]: Canceling download Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.660 [1346.1346] INFO SampleApp: Playback state changed: Stopped Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.660 [1346.1346] INFO StreamingEventReporter: [0x132d1a0]: Reporting streaming ended event Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO HttpDownloader: [0x132d1f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO SampleApp: [Stream 3]: Audio stream has been disposed of Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO MediaEngine: [0x132c648]: Setting current track: 337591033, initial offset: 0ms Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO AudioStreamManager: [0x132c730]: Clearing all streams Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO AudioStreamManager: [0x132c730]: New stream: 5 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO HttpDownloader: [0x1471a80]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=cf3a8455b70255eb5f9023e34612a415&request_ts=1766517657&track_id=337591033 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO HttpDownloader: [0x1363ab0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=337591033 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO HttpDownloader: [0x18c8ba8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6ea5422731017ebec7c689e4304441ad&request_ts=1766517657&track_id=337591033 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 5]: Running audio stream Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.661 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:57 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.663 [1346.1346] INFO MediaEngine: [0x132c648]: Setting next track: 337591034 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.663 [1346.1346] INFO AudioStreamManager: [0x132c730]: New stream: 6 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.664 [1346.1346] INFO HttpDownloader: [0x13df880]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=abc85c57baea7cf79a208e13e0352ced&request_ts=1766517657&track_id=337591034 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.664 [1346.1346] INFO HttpDownloader: [0x186a138]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=337591034 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.664 [1346.1346] INFO HttpDownloader: [0x135fe68]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=75db26417fdfff5278e51f1d85182044&request_ts=1766517657&track_id=337591034 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.665 [1346.1346] INFO MediaEngine: [0x132c648]: Waiting for current stream to start before starting audio renderer Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:57 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.748 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=337591033&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521257&hmac=B08WRJ6glfA_VV2sBFaBUWAWlhQ Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.802 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=337591034&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521257&hmac=d7hCDTBROCYScr04RKEGxHGNMe0 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.805 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 5]: stream information have been fetched Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.805 [1346.1346] INFO UrlAudioSource: [0x18fc848]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=337591033&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521257&hmac=B08WRJ6glfA_VV2sBFaBUWAWlhQ Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.805 [1346.1346] INFO ContentFetcher: [0x18122d0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3768619&eid=337591033&fmt=7&profile=raw&app_id=174516466&cid=2675207&etsp=1766521257&hmac=B08WRJ6glfA_VV2sBFaBUWAWlhQ, offset: 0 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.805 [1346.1346] INFO AudioRenderer: [0x132c868]: Starting audio renderer, initial playback state: Playing Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.805 [1346.1346] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: QobuzConnect: alread currrent service, send playback confirmation Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.807 [1346.1346] INFO SampleApp: Initiating playback, initial state: Playing Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.812 [1346.1346] INFO SampleApp: Playback state changed: Playing Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.812 [1346.1346] INFO StreamingEventReporter: [0x132d1a0]: Reporting streaming started event Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.812 [1346.1346] INFO ProtocolHandler: [0x132cd88]: Need to send state update Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.812 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending StateUpdated message Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.812 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.812 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending FileAudioQualityChanged message Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.813 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.813 [1346.1346] INFO QConnectMessageSender: [0x140bf88]: Sending DeviceAudioQualityChanged message Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.813 [1346.1346] INFO QwspMessageSender: [0x1422648]: Sending Payload message Dec 23 20:20:57 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:57 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:57 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::servicePushState Dec 23 20:20:57 volumio volumio[846]: info: CoreStateMachine::pushState Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Dec 23 20:20:57 volumio volumio[846]: info: MRS: Pushing multiroomSync output Dec 23 20:20:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Dec 23 20:20:57 volumio volumio[846]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Dec 23 20:20:57 volumio volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 20:20:57 volumio volumio[846]: TypeError: Cannot read property 'indexOf' of undefined Dec 23 20:20:57 volumio volumio[846]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Dec 23 20:20:57 volumio volumio[846]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Dec 23 20:20:57 volumio volumio[846]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Dec 23 20:20:57 volumio volumio[846]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Dec 23 20:20:57 volumio volumio[846]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Dec 23 20:20:57 volumio volumio[846]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Dec 23 20:20:57 volumio volumio[846]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Dec 23 20:20:57 volumio volumio[846]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Dec 23 20:20:57 volumio volumio[846]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Dec 23 20:20:57 volumio volumio[846]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Dec 23 20:20:57 volumio volumio[846]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Dec 23 20:20:57 volumio volumio[846]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Dec 23 20:20:57 volumio volumio[846]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Dec 23 20:20:57 volumio volumio[846]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Dec 23 20:20:57 volumio volumio[846]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Dec 23 20:20:57 volumio volumio[846]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Dec 23 20:20:57 volumio volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.917 [1346.1346] INFO HttpDownloader: [0x132d1f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.918 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 6]: Metadata became available: Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.918 [1346.1346] INFO AudioStreamManager: [0x132c730]: Title: Everything Means Nothing to Me Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.918 [1346.1346] INFO AudioStreamManager: [0x132c730]: Artist: Brad Mehldau Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.918 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album: Ride into the Sun Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.918 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album art URL: https://static.qobuz.com/images/covers/wa/1z/ov6m8obwm1zwa_600.jpg Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.918 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 6]: stream information have been fetched Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO AudioStreamManager: [0x132c730]: [Stream 5]: Metadata became available: Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO AudioStreamManager: [0x132c730]: Title: Better Be Quiet Now Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO AudioStreamManager: [0x132c730]: Artist: Brad Mehldau Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album: Ride into the Sun Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO AudioStreamManager: [0x132c730]: Album art URL: https://static.qobuz.com/images/covers/wa/1z/ov6m8obwm1zwa_600.jpg Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO SampleApp: [Stream 5]: Stream metadata became available: Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO SampleApp: Title: Better Be Quiet Now Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO SampleApp: Artist: Brad Mehldau Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO SampleApp: Album: Ride into the Sun Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.919 [1346.1346] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/wa/1z/ov6m8obwm1zwa_600.jpg Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.954 [1346.1346] INFO UrlAudioSource: [0x18fc848]: Content info extracted Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.954 [1346.1346] INFO UrlAudioSource: [0x18fc848]: Size: 85884777 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.955 [1346.21894] INFO AudioDecoder: [0x1a23e78]: Stream info: Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.955 [1346.21894] INFO AudioDecoder: [0x1a23e78]: Total samples: 23372117 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.956 [1346.21894] INFO AudioDecoder: [0x1a23e78]: Sample rate: 96000 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.956 [1346.21894] INFO AudioDecoder: [0x1a23e78]: Channel count: 2 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.956 [1346.21894] INFO AudioDecoder: [0x1a23e78]: Bits per sample: 24 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.956 [1346.21894] INFO AudioDecoder: [0x1a23e78]: Duration: 243459 Dec 23 20:20:57 volumio qobuz-connect[1346]: 20251223 20:20:57.956 [1346.1346] INFO UrlAudioSource: [0x18fc848]: Audio properties have changed Dec 23 20:20:58 volumio sudo[21897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 20:19 Dec 23 20:20:58 volumio sudo[21897]: 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"