-- Logs begin at Thu 2025-05-22 16:32:21 CEST, end at Thu 2025-05-22 17:14:26 CEST. -- May 22 17:13:02 volumio qobuz-connect[1939]: 20250522 17:13:02.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:13:02 volumio qobuz-connect[1939]: 20250522 17:13:02.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:13:02 volumio qobuz-connect[1939]: 20250522 17:13:02.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:13:02 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:02 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:13:02 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:13:02 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:13:02 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:13:02 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:13:02 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:13:02 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:12 volumio qobuz-connect[1939]: 20250522 17:13:12.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:13:12 volumio qobuz-connect[1939]: 20250522 17:13:12.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:13:12 volumio qobuz-connect[1939]: 20250522 17:13:12.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:13:12 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:12 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:13:12 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:13:12 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:13:12 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:13:12 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:13:12 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:13:12 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:22 volumio qobuz-connect[1939]: 20250522 17:13:22.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:13:22 volumio qobuz-connect[1939]: 20250522 17:13:22.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:13:22 volumio qobuz-connect[1939]: 20250522 17:13:22.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:13:22 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:22 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:13:22 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:13:22 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:13:22 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:13:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:13:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:13:22 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:26 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:13:26 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:13:26 volumio volumio[1072]: info: Discovery: Getting this device information May 22 17:13:26 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:26 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:13:32 volumio qobuz-connect[1939]: 20250522 17:13:32.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:13:32 volumio qobuz-connect[1939]: 20250522 17:13:32.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:13:32 volumio qobuz-connect[1939]: 20250522 17:13:32.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:13:32 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:32 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:13:32 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:13:32 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:13:32 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:13:32 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:13:32 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:13:32 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:42 volumio qobuz-connect[1939]: 20250522 17:13:42.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:13:42 volumio qobuz-connect[1939]: 20250522 17:13:42.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:13:42 volumio qobuz-connect[1939]: 20250522 17:13:42.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:13:42 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:42 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:13:42 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:13:42 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:13:42 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:13:42 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:13:42 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:13:42 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:52 volumio qobuz-connect[1939]: 20250522 17:13:52.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:13:52 volumio qobuz-connect[1939]: 20250522 17:13:52.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:13:52 volumio qobuz-connect[1939]: 20250522 17:13:52.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:13:52 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:52 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:13:52 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:13:52 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:13:52 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:13:52 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:13:52 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:13:52 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:13:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:13:56 volumio volumio[1072]: info: Discovery: Getting this device information May 22 17:13:56 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:13:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:14:02 volumio qobuz-connect[1939]: 20250522 17:14:02.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:14:02 volumio qobuz-connect[1939]: 20250522 17:14:02.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:14:02 volumio qobuz-connect[1939]: 20250522 17:14:02.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:14:02 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:02 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:02 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:02 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:02 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:02 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:02 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:02 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:12 volumio qobuz-connect[1939]: 20250522 17:14:12.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:14:12 volumio qobuz-connect[1939]: 20250522 17:14:12.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:14:12 volumio qobuz-connect[1939]: 20250522 17:14:12.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:14:12 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:12 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:12 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:12 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:12 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:12 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:12 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:12 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:22 volumio qobuz-connect[1939]: 20250522 17:14:22.698 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:14:22 volumio qobuz-connect[1939]: 20250522 17:14:22.698 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:14:22 volumio qobuz-connect[1939]: 20250522 17:14:22.698 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:14:22 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:22 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:22 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:22 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:22 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:22 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Received SetState message: May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Playing state: Playing May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Playback position: 0 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Queue version: 24.2 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Current track: TID: 324746036, QID: 8, Context UUID: 63a64222-d22f-4540-8f60-2aabaf49b768 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Next track: TID: 324746037, QID: 9, Context UUID: 63a64222-d22f-4540-8f60-2aabaf49b768 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO MediaEngine: [0x55eed161fd50]: Stopping playback, clearing tracks May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO MediaEngine: [0x55eed161fd50]: Initiating playback May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO AudioRenderer: [0x55eed1620150]: Stopping audio renderer May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.326 [1939.1939] INFO SampleApp: Stopping playback May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.334 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Clearing all streams May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.334 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 23]: Disposing of audio stream May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.334 [1939.1939] INFO ContentFetcher: [0x55eed16843b0]: Canceling download May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.336 [1939.1939] INFO SampleApp: Playback state changed: Stopped May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.336 [1939.1939] INFO StreamingEventReporter: [0x55eed1621240]: Reporting streaming ended event May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.336 [1939.1939] INFO HttpDownloader: [0x55eed16212d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.336 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.336 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.336 [1939.1939] INFO SampleApp: [Stream 23]: Audio stream has been disposed of May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO MediaEngine: [0x55eed161fd50]: Setting current track: 324746036, initial offset: 0ms May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Clearing all streams May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: New stream: 25 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO HttpDownloader: [0x55eed17e5110]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=fb9249e3003d3fa27dd9fbc13270acb2&request_ts=1747926865&track_id=324746036 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO HttpDownloader: [0x55eed17a6e50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=324746036 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO HttpDownloader: [0x55eed1d23270]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=add30d81d6ff278829121c65ba2f424b&request_ts=1747926865&track_id=324746036 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 25]: Running audio stream May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.337 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:25 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.340 [1939.1939] INFO MediaEngine: [0x55eed161fd50]: Setting next track: 324746037 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.340 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: New stream: 26 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.340 [1939.1939] INFO HttpDownloader: [0x55eed17feef0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=fcf6fc5e9c8ecc831bd48ca8322c71bf&request_ts=1747926865&track_id=324746037 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.340 [1939.1939] INFO HttpDownloader: [0x55eed18134f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=324746037 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.340 [1939.1939] INFO HttpDownloader: [0x55eed17ee660]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=026b8d598d06618d8f4bf93daf2b4004&request_ts=1747926865&track_id=324746037 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.342 [1939.1939] INFO MediaEngine: [0x55eed161fd50]: Waiting for current stream to start before starting audio renderer May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:25 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.663 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 26]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2381286&eid=324746037&fmt=7&profile=raw&app_id=174516466&cid=1877224&etsp=1747930465&hmac=D3Pi6wKO2sUvJRmZ3o_3xEJGA4w May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.711 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 25]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2381286&eid=324746036&fmt=7&profile=raw&app_id=174516466&cid=1877224&etsp=1747930465&hmac=9JuyL1Vj6K_v_e5jWwpxySZpSYA May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.739 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 26]: stream information have been fetched May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.773 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 25]: stream information have been fetched May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.773 [1939.1939] INFO UrlAudioSource: [0x55eed17e8a60]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2381286&eid=324746036&fmt=7&profile=raw&app_id=174516466&cid=1877224&etsp=1747930465&hmac=9JuyL1Vj6K_v_e5jWwpxySZpSYA May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.773 [1939.1939] INFO ContentFetcher: [0x55eed1b931b0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2381286&eid=324746036&fmt=7&profile=raw&app_id=174516466&cid=1877224&etsp=1747930465&hmac=9JuyL1Vj6K_v_e5jWwpxySZpSYA, offset: 0 May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.774 [1939.1939] INFO AudioRenderer: [0x55eed1620150]: Starting audio renderer, initial playback state: Playing May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.774 [1939.1939] INFO SampleApp: [Stream 25]: New audio stream (starting from 0ms) May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: QobuzConnect: alread currrent service, send playback confirmation May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.775 [1939.1939] INFO SampleApp: Initiating playback, initial state: Playing May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.926 [1939.1939] INFO SampleApp: Playback state changed: Playing May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.926 [1939.1939] INFO StreamingEventReporter: [0x55eed1621240]: Reporting streaming started event May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.926 [1939.1939] INFO ProtocolHandler: [0x55eed1620aa0]: Need to send state update May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.926 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending StateUpdated message May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.926 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.926 [1939.1939] INFO QConnectMessageSender: [0x55eed1684200]: Sending FileAudioQualityChanged message May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.926 [1939.1939] INFO QwspMessageSender: [0x55eed16841e0]: Sending Payload message May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.927 [1939.1939] INFO HttpDownloader: [0x55eed16212d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:25 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 25]: Metadata became available: May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Title: Brothers In Arms May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Artist: Dire Straits May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Album: Brothers In Arms May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Album art URL: https://static.qobuz.com/images/covers/zb/ur/jb1fbzml9urzb_600.jpg May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: [Stream 26]: Metadata became available: May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Title: Ride Across The River (San Antonio Live In 85) May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Artist: Dire Straits May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Album: Brothers In Arms (San Antonio Live In 85) May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO AudioStreamManager: [0x55eed161fef0]: Album art URL: https://static.qobuz.com/images/covers/zb/ur/jb1fbzml9urzb_600.jpg May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO SampleApp: [Stream 25]: Stream metadata became available: May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO SampleApp: Title: Brothers In Arms May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO SampleApp: Artist: Dire Straits May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO SampleApp: Album: Brothers In Arms May 22 17:14:25 volumio qobuz-connect[1939]: 20250522 17:14:25.928 [1939.1939] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/zb/ur/jb1fbzml9urzb_600.jpg May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:25 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:25 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:25 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 22 17:14:25 volumio volumio[1072]: info: CoreStateMachine::pushState May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device May 22 17:14:25 volumio volumio[1072]: info: MRS: Pushing multiroomSync output May 22 17:14:25 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState May 22 17:14:25 volumio volumio[1072]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 22 17:14:25 volumio volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:14:25 volumio volumio[1072]: TypeError: Cannot read property 'indexOf' of undefined May 22 17:14:25 volumio volumio[1072]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 22 17:14:25 volumio volumio[1072]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 22 17:14:25 volumio volumio[1072]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 22 17:14:25 volumio volumio[1072]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 22 17:14:25 volumio volumio[1072]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 22 17:14:25 volumio volumio[1072]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 22 17:14:25 volumio volumio[1072]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 22 17:14:25 volumio volumio[1072]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 22 17:14:25 volumio volumio[1072]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 22 17:14:25 volumio volumio[1072]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 22 17:14:25 volumio volumio[1072]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 22 17:14:25 volumio volumio[1072]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 22 17:14:25 volumio volumio[1072]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 22 17:14:25 volumio volumio[1072]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 22 17:14:25 volumio volumio[1072]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 22 17:14:25 volumio volumio[1072]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 22 17:14:25 volumio volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.034 [1939.1939] INFO UrlAudioSource: [0x55eed17e8a60]: Content info extracted May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.1939] INFO UrlAudioSource: [0x55eed17e8a60]: Size: 75113564 May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.7687] INFO AudioDecoder: [0x55eed18b6070]: Stream info: May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.7687] INFO AudioDecoder: [0x55eed18b6070]: Total samples: 18561396 May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.7687] INFO AudioDecoder: [0x55eed18b6070]: Sample rate: 44100 May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.7687] INFO AudioDecoder: [0x55eed18b6070]: Channel count: 2 May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.7687] INFO AudioDecoder: [0x55eed18b6070]: Bits per sample: 24 May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.7687] INFO AudioDecoder: [0x55eed18b6070]: Duration: 420893 May 22 17:14:26 volumio qobuz-connect[1939]: 20250522 17:14:26.035 [1939.1939] INFO UrlAudioSource: [0x55eed17e8a60]: Audio properties have changed May 22 17:14:26 volumio sudo[7689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:13 May 22 17:14:26 volumio sudo[7689]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"