-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Fri 2025-05-09 17:43:21 BST. -- May 09 17:42:03 officeaudio qobuz-connect[1655]: 20250509 17:42:03.011 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:42:03 officeaudio qobuz-connect[1655]: 20250509 17:42:03.011 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:42:03 officeaudio qobuz-connect[1655]: 20250509 17:42:03.011 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:42:03 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:03 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:42:03 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:42:03 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:42:03 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:42:03 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:13 officeaudio qobuz-connect[1655]: 20250509 17:42:13.011 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:42:13 officeaudio qobuz-connect[1655]: 20250509 17:42:13.011 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:42:13 officeaudio qobuz-connect[1655]: 20250509 17:42:13.011 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:42:13 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:13 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:42:13 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:42:13 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:42:13 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:42:13 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:23 officeaudio qobuz-connect[1655]: 20250509 17:42:23.010 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:42:23 officeaudio qobuz-connect[1655]: 20250509 17:42:23.011 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:42:23 officeaudio qobuz-connect[1655]: 20250509 17:42:23.011 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:42:23 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:23 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:42:23 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:42:23 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:42:23 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:42:23 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:33 officeaudio qobuz-connect[1655]: 20250509 17:42:33.011 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:42:33 officeaudio qobuz-connect[1655]: 20250509 17:42:33.011 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:42:33 officeaudio qobuz-connect[1655]: 20250509 17:42:33.011 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:42:33 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:33 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:42:33 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:42:33 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:42:33 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:42:33 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:34 officeaudio qobuz-connect[1655]: 20250509 17:42:34.497 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 12]: Audio stream has finished May 09 17:42:34 officeaudio qobuz-connect[1655]: 20250509 17:42:34.497 [1655.1655] INFO SampleApp: [Stream 12]: Audio stream finished May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.005 [1655.1655] INFO SampleApp: Playback is nearly finished May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.504 [1655.1655] INFO SampleApp: Playback has finished May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.504 [1655.1655] INFO SampleApp: Nothing else to play, stopping playback May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.504 [1655.1655] ERROR SampleApp: Unable to drain buffered PCM audio data from ALSA audio sink: Resource temporarily unavailable (-11) May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 12]: Disposing of audio stream May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO ContentFetcher: [0x10ca188]: Canceling download May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO SampleApp: [Stream 12]: Audio stream has been disposed of May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO StreamingEventReporter: [0xe1f368]: Reporting streaming ended event May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO HttpDownloader: [0xe1f3c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.521 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.527 [1655.1655] INFO SampleApp: Playback state changed: Stopped May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Received SetState message: May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Playing state: Paused May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Playback position: May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Queue version: 7.1 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Current track: TID: 328886783, QID: 0, Context UUID: fed12f33-b1a1-4e69-be4c-7ce5d36ce41f May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Next track: TID: 328886784, QID: 1, Context UUID: fed12f33-b1a1-4e69-be4c-7ce5d36ce41f May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO MediaEngine: [0xe1e810]: Stopping playback, clearing tracks May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO MediaEngine: [0xe1e810]: Initiating playback May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO RendererActionAvailabilityManager: [0xe1eea0]: Renderer action 'Next' is available May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO MediaEngine: [0xe1e810]: Setting current track: 328886783, initial offset: 0ms May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Clearing all streams May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: New stream: 13 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO HttpDownloader: [0xec21a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=07c60a89f54e0b9fbcd8659df78acadb&request_ts=1746808956&track_id=328886783 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO HttpDownloader: [0x103f850]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=328886783 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO HttpDownloader: [0xed20c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b183decf737aff3f7b066d4299c548a1&request_ts=1746808956&track_id=328886783 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.553 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 13]: Running audio stream May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.554 [1655.1655] INFO MediaEngine: [0xe1e810]: Setting next track: 328886784 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.554 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: New stream: 14 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.554 [1655.1655] INFO HttpDownloader: [0xfbde90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9f35a23e00ad3eedc1c8491d8970d21f&request_ts=1746808956&track_id=328886784 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.554 [1655.1655] INFO HttpDownloader: [0xec7d28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=328886784 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.554 [1655.1655] INFO HttpDownloader: [0xed07f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=dba586d671d66933c9c63a8db7dff088&request_ts=1746808956&track_id=328886784 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.554 [1655.1655] INFO MediaEngine: [0xe1e810]: Waiting for current stream to start before starting audio renderer May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.897 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 14]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=328886784&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1746812556&hmac=b-7sf8AEVvrUTB0sPHN0k4GoQ4A May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.935 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 13]: stream information have been fetched May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.951 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 13]: Metadata became available: May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.951 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Title: Swoon May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.951 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Artist: Peter Murphy May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.951 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album: Silver Shade May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.951 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album art URL: https://static.qobuz.com/images/covers/ob/xg/poqn9zvgtxgob_600.jpg May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.967 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 13]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=328886783&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1746812556&hmac=DVqpKzFScCynDmGAA9bESaAuIXQ May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.967 [1655.1655] INFO UrlAudioSource: [0x11c5528]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=328886783&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1746812556&hmac=DVqpKzFScCynDmGAA9bESaAuIXQ May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.967 [1655.1655] INFO ContentFetcher: [0x103f5f8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=328886783&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1746812556&hmac=DVqpKzFScCynDmGAA9bESaAuIXQ, offset: 0 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.967 [1655.1655] INFO AudioRenderer: [0xe1ea30]: Starting audio renderer, initial playback state: Paused May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.967 [1655.1655] INFO SampleApp: [Stream 13]: New audio stream (starting from 0ms) May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.967 [1655.1655] INFO SampleApp: [Stream 13]: Stream metadata became available: May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.967 [1655.1655] INFO SampleApp: Title: Swoon May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.968 [1655.1655] INFO SampleApp: Artist: Peter Murphy May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.968 [1655.1655] INFO SampleApp: Album: Silver Shade May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.968 [1655.1655] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ob/xg/poqn9zvgtxgob_600.jpg May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio volumio[1206]: info: QobuzConnect: alread currrent service, send playback confirmation May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.969 [1655.1655] INFO SampleApp: Initiating playback, initial state: Paused May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.972 [1655.1655] INFO SampleApp: Playback state changed: Paused May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.972 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.972 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.972 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.972 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending FileAudioQualityChanged message May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.972 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.973 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 14]: Metadata became available: May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.973 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Title: Hot Roy May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.973 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Artist: Peter Murphy May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.973 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album: Silver Shade May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.973 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album art URL: https://static.qobuz.com/images/covers/ob/xg/poqn9zvgtxgob_600.jpg May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:42:36 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.1655] INFO UrlAudioSource: [0x11c5528]: Content info extracted May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.1655] INFO UrlAudioSource: [0x11c5528]: Size: 34856663 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.10216] INFO AudioDecoder: [0x12fc370]: Stream info: May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.10216] INFO AudioDecoder: [0x12fc370]: Total samples: 13975584 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.10216] INFO AudioDecoder: [0x12fc370]: Sample rate: 44100 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.10216] INFO AudioDecoder: [0x12fc370]: Channel count: 2 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.10216] INFO AudioDecoder: [0x12fc370]: Bits per sample: 16 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.10216] INFO AudioDecoder: [0x12fc370]: Duration: 316906 May 09 17:42:36 officeaudio qobuz-connect[1655]: 20250509 17:42:36.987 [1655.1655] INFO UrlAudioSource: [0x11c5528]: Audio properties have changed May 09 17:42:37 officeaudio qobuz-connect[1655]: 20250509 17:42:37.016 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 14]: stream information have been fetched May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Received SetState message: May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Playing state: Playing May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Playback position: 0 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Queue version: 8.1 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Current track: TID: 320611354, QID: 0, Context UUID: 92351434-da78-4543-b126-4792a3f46a85 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Next track: TID: 320611355, QID: 1, Context UUID: 92351434-da78-4543-b126-4792a3f46a85 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO MediaEngine: [0xe1e810]: Stopping playback, clearing tracks May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO MediaEngine: [0xe1e810]: Initiating playback May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO AudioRenderer: [0xe1ea30]: Stopping audio renderer May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.033 [1655.1655] INFO SampleApp: Stopping playback May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.051 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Clearing all streams May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.051 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 13]: Disposing of audio stream May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.051 [1655.1655] INFO ContentFetcher: [0x103f5f8]: Canceling download May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.054 [1655.1655] INFO SampleApp: Playback state changed: Stopped May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.054 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.054 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO SampleApp: [Stream 13]: Audio stream has been disposed of May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO MediaEngine: [0xe1e810]: Setting current track: 320611354, initial offset: 0ms May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Clearing all streams May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: New stream: 15 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO HttpDownloader: [0xe2a050]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=cd8e141d7b542b0cf3183af7b21f28d6&request_ts=1746809001&track_id=320611354 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO HttpDownloader: [0xe8a668]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=320611354 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO HttpDownloader: [0x132c378]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=226d3948ab974a3af2a39e8b65af1afd&request_ts=1746809001&track_id=320611354 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 15]: Running audio stream May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO MediaEngine: [0xe1e810]: Setting next track: 320611355 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: New stream: 16 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO HttpDownloader: [0xec21a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e19b17f02512fc7c022bb4d32bf1c355&request_ts=1746809001&track_id=320611355 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO HttpDownloader: [0x126be38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=320611355 May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.055 [1655.1655] INFO HttpDownloader: [0x1232b48]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=f611e2652d50ac09c99b40d7bb9ab1e3&request_ts=1746809001&track_id=320611355 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.057 [1655.1655] INFO MediaEngine: [0xe1e810]: Waiting for current stream to start before starting audio renderer May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.207 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 16]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=320611355&fmt=7&profile=raw&app_id=174516466&cid=1669468&etsp=1746812601&hmac=CdgYw7nw6Di3v_8LKdKuA6Ku6EE May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.210 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 15]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=320611354&fmt=7&profile=raw&app_id=174516466&cid=1669468&etsp=1746812601&hmac=xY8PdAoLpyu0pNhW55cs6DTvugo May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.219 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 15]: stream information have been fetched May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.219 [1655.1655] INFO UrlAudioSource: [0x11c58b8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=320611354&fmt=7&profile=raw&app_id=174516466&cid=1669468&etsp=1746812601&hmac=xY8PdAoLpyu0pNhW55cs6DTvugo May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.219 [1655.1655] INFO ContentFetcher: [0x116df58]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=320611354&fmt=7&profile=raw&app_id=174516466&cid=1669468&etsp=1746812601&hmac=xY8PdAoLpyu0pNhW55cs6DTvugo, offset: 0 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.219 [1655.1655] INFO AudioRenderer: [0xe1ea30]: Starting audio renderer, initial playback state: Playing May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.219 [1655.1655] INFO SampleApp: [Stream 15]: New audio stream (starting from 0ms) May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: QobuzConnect: alread currrent service, send playback confirmation May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.220 [1655.1655] INFO SampleApp: Initiating playback, initial state: Playing May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.223 [1655.1655] INFO SampleApp: Playback state changed: Playing May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO StreamingEventReporter: [0xe1f368]: Reporting streaming started event May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO HttpDownloader: [0xe1f3c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO ProtocolHandler: [0xe1ef50]: Need to send state update May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending StateUpdated message May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending FileAudioQualityChanged message May 09 17:43:21 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.224 [1655.1655] INFO QConnectMessageSender: [0xe5e978]: Sending DeviceAudioQualityChanged message May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.225 [1655.1655] INFO QwspMessageSender: [0xf28ed8]: Sending Payload message May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.225 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 16]: stream information have been fetched May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::servicePushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreStateMachine::pushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioPushState May 09 17:43:21 officeaudio volumio[1206]: info: CoreCommandRouter::volumioGetState May 09 17:43:21 officeaudio volumio[1206]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 09 17:43:21 officeaudio volumio[1206]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 17:43:21 officeaudio volumio[1206]: TypeError: Cannot read property 'indexOf' of undefined May 09 17:43:21 officeaudio volumio[1206]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 09 17:43:21 officeaudio volumio[1206]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 09 17:43:21 officeaudio volumio[1206]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 09 17:43:21 officeaudio volumio[1206]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 09 17:43:21 officeaudio volumio[1206]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 09 17:43:21 officeaudio volumio[1206]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 09 17:43:21 officeaudio volumio[1206]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 09 17:43:21 officeaudio volumio[1206]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 09 17:43:21 officeaudio volumio[1206]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 09 17:43:21 officeaudio volumio[1206]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 09 17:43:21 officeaudio volumio[1206]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 09 17:43:21 officeaudio volumio[1206]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 09 17:43:21 officeaudio volumio[1206]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 09 17:43:21 officeaudio volumio[1206]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 09 17:43:21 officeaudio volumio[1206]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 09 17:43:21 officeaudio volumio[1206]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 09 17:43:21 officeaudio volumio[1206]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.262 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 15]: Metadata became available: May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.262 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Title: Look To Windward May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.262 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Artist: Sleep Token May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.262 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album: Even In Arcadia May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.262 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album art URL: https://static.qobuz.com/images/covers/ma/g5/zo922etceg5ma_600.jpg May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.263 [1655.1655] INFO SampleApp: [Stream 15]: Stream metadata became available: May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.263 [1655.1655] INFO SampleApp: Title: Look To Windward May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.263 [1655.1655] INFO SampleApp: Artist: Sleep Token May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.263 [1655.1655] INFO SampleApp: Album: Even In Arcadia May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.263 [1655.1655] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ma/g5/zo922etceg5ma_600.jpg May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.308 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: [Stream 16]: Metadata became available: May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.309 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Title: Emergence May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.309 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Artist: Sleep Token May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.309 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album: Even In Arcadia May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.309 [1655.1655] INFO AudioStreamManager: [0xe1e8f8]: Album art URL: https://static.qobuz.com/images/covers/ma/g5/zo922etceg5ma_600.jpg May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.1655] INFO UrlAudioSource: [0x11c58b8]: Content info extracted May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.1655] INFO UrlAudioSource: [0x11c58b8]: Size: 87025290 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.10331] INFO AudioDecoder: [0xffc250]: Stream info: May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.10331] INFO AudioDecoder: [0xffc250]: Total samples: 20571054 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.10331] INFO AudioDecoder: [0xffc250]: Sample rate: 44100 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.10331] INFO AudioDecoder: [0xffc250]: Channel count: 2 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.10331] INFO AudioDecoder: [0xffc250]: Bits per sample: 24 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.10331] INFO AudioDecoder: [0xffc250]: Duration: 466463 May 09 17:43:21 officeaudio qobuz-connect[1655]: 20250509 17:43:21.329 [1655.1655] INFO UrlAudioSource: [0x11c58b8]: Audio properties have changed May 09 17:43:21 officeaudio sudo[10333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 17:42 May 09 17:43:21 officeaudio sudo[10333]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"