-- 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"