-- Logs begin at Thu 2019-02-14 08:12:00 -02, end at Thu 2025-06-05 22:39:27 -03. -- Jun 05 22:38:08 volumio qobuz-connect[1598]: 20250605 22:38:08.831 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:38:08 volumio qobuz-connect[1598]: 20250605 22:38:08.831 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:38:08 volumio qobuz-connect[1598]: 20250605 22:38:08.832 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:08 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:08 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:08 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:08 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:08 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:08 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:18 volumio qobuz-connect[1598]: 20250605 22:38:18.832 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:38:18 volumio qobuz-connect[1598]: 20250605 22:38:18.832 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:38:18 volumio qobuz-connect[1598]: 20250605 22:38:18.832 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:18 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:18 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:18 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:18 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:18 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:18 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:18 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:18 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:28 volumio qobuz-connect[1598]: 20250605 22:38:28.832 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:38:28 volumio qobuz-connect[1598]: 20250605 22:38:28.832 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:38:28 volumio qobuz-connect[1598]: 20250605 22:38:28.832 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:28 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:28 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:28 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:28 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:28 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:28 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:28 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:28 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:38 volumio qobuz-connect[1598]: 20250605 22:38:38.831 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:38:38 volumio qobuz-connect[1598]: 20250605 22:38:38.832 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:38:38 volumio qobuz-connect[1598]: 20250605 22:38:38.832 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:38 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:38 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:38 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:38 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:38 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:38 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:38 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.469 [1598.1598] INFO ProtocolHandler: [0xe12988]: Received SetState message: Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.469 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playing state: Playing Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.469 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playback position: 0 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.470 [1598.1598] INFO ProtocolHandler: [0xe12988]: Queue version: 4.1 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.470 [1598.1598] INFO ProtocolHandler: [0xe12988]: Current track: TID: 54832, QID: 0, Context UUID: da0215da-74a2-45c0-8931-72b78ebc990f Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.470 [1598.1598] INFO ProtocolHandler: [0xe12988]: Next track: TID: 54833, QID: 1, Context UUID: da0215da-74a2-45c0-8931-72b78ebc990f Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.470 [1598.1598] INFO MediaEngine: [0xe12248]: Stopping playback, clearing tracks Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.470 [1598.1598] INFO MediaEngine: [0xe12248]: Initiating playback Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.470 [1598.1598] INFO AudioRenderer: [0xe12468]: Stopping audio renderer Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.470 [1598.1598] INFO SampleApp: Stopping playback Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.476 [1598.1598] INFO AudioStreamManager: [0xe12330]: Clearing all streams Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.476 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 39]: Disposing of audio stream Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.476 [1598.1598] INFO ContentFetcher: [0xffd888]: Canceling download Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.492 [1598.1598] INFO SampleApp: Playback state changed: Stopped Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.492 [1598.1598] INFO StreamingEventReporter: [0xe12da0]: Reporting streaming ended event Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.493 [1598.1598] INFO HttpDownloader: [0xe12df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.494 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.494 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.494 [1598.1598] INFO SampleApp: [Stream 39]: Audio stream has been disposed of Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.494 [1598.1598] INFO MediaEngine: [0xe12248]: Setting current track: 54832, initial offset: 0ms Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.494 [1598.1598] INFO AudioStreamManager: [0xe12330]: Clearing all streams Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.494 [1598.1598] INFO AudioStreamManager: [0xe12330]: New stream: 41 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.495 [1598.1598] INFO HttpDownloader: [0xe1b058]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=18ed64272189c3e4f6330d40a5223ffc&request_ts=1749173921&track_id=54832 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.495 [1598.1598] INFO HttpDownloader: [0x132b750]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=54832 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.495 [1598.1598] INFO HttpDownloader: [0x12ce400]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e7c32c208233fb0bd6416137125c8c4c&request_ts=1749173921&track_id=54832 Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:41 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.496 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 41]: Running audio stream Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.496 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.502 [1598.1598] INFO MediaEngine: [0xe12248]: Setting next track: 54833 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.502 [1598.1598] INFO AudioStreamManager: [0xe12330]: New stream: 42 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.502 [1598.1598] INFO HttpDownloader: [0x13b07f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=3c1aa5ea2983c4c1859d1795b52bea9a&request_ts=1749173921&track_id=54833 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.503 [1598.1598] INFO HttpDownloader: [0x12c9320]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=54833 Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.503 [1598.1598] INFO HttpDownloader: [0xe3f5f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=3e66d95bc8f613d69f51ca79a7806869&request_ts=1749173921&track_id=54833 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.507 [1598.1598] INFO MediaEngine: [0xe12248]: Waiting for current stream to start before starting audio renderer Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:41 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.763 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 41]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=54832&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749177521&hmac=WXcQ7uLeQVMJbEcyaBrohcuA1Bc Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.828 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 41]: Metadata became available: Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.828 [1598.1598] INFO AudioStreamManager: [0xe12330]: Title: Eternity's Breath Part 1 (Album Version) Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.828 [1598.1598] INFO AudioStreamManager: [0xe12330]: Artist: Mahavishnu Orchestra Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.828 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album: Visions of the Emerald Beyond (Album Version) Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.829 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album art URL: https://static.qobuz.com/images/covers/22/67/0074644686722_600.jpg Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.841 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 41]: stream information have been fetched Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.841 [1598.1598] INFO UrlAudioSource: [0xe1cb48]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=54832&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749177521&hmac=WXcQ7uLeQVMJbEcyaBrohcuA1Bc Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.841 [1598.1598] INFO ContentFetcher: [0x1096fb0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=54832&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749177521&hmac=WXcQ7uLeQVMJbEcyaBrohcuA1Bc, offset: 0 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.842 [1598.1598] INFO AudioRenderer: [0xe12468]: Starting audio renderer, initial playback state: Playing Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.843 [1598.1598] INFO SampleApp: [Stream 41]: New audio stream (starting from 0ms) Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.843 [1598.1598] INFO SampleApp: [Stream 41]: Stream metadata became available: Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.843 [1598.1598] INFO SampleApp: Title: Eternity's Breath Part 1 (Album Version) Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.843 [1598.1598] INFO SampleApp: Artist: Mahavishnu Orchestra Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.843 [1598.1598] INFO SampleApp: Album: Visions of the Emerald Beyond (Album Version) Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.844 [1598.1598] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/22/67/0074644686722_600.jpg Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.849 [1598.1598] INFO SampleApp: Initiating playback, initial state: Playing Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.880 [1598.1598] INFO SampleApp: Playback state changed: Playing Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.881 [1598.1598] INFO StreamingEventReporter: [0xe12da0]: Reporting streaming started event Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.881 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.881 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.882 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.882 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending FileAudioQualityChanged message Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.883 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.883 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending DeviceAudioQualityChanged message Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.884 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:41 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.912 [1598.1598] INFO UrlAudioSource: [0xe1cb48]: Content info extracted Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.912 [1598.1598] INFO UrlAudioSource: [0xe1cb48]: Size: 67228669 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.914 [1598.20002] INFO AudioDecoder: [0x150cf90]: Stream info: Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.914 [1598.20002] INFO AudioDecoder: [0x150cf90]: Total samples: 18302404 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.914 [1598.20002] INFO AudioDecoder: [0x150cf90]: Sample rate: 96000 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.914 [1598.20002] INFO AudioDecoder: [0x150cf90]: Channel count: 2 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.914 [1598.20002] INFO AudioDecoder: [0x150cf90]: Bits per sample: 24 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.914 [1598.20002] INFO AudioDecoder: [0x150cf90]: Duration: 190650 Jun 05 22:38:41 volumio qobuz-connect[1598]: 20250605 22:38:41.915 [1598.1598] INFO UrlAudioSource: [0xe1cb48]: Audio properties have changed Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:41 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:41 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:41 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:41 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:41 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:42 volumio volumio[880]: info: [LastFM] Current track has sufficient metadata: title (Eternity's Breath Part 1 (Album Version)) and artist (Mahavishnu Orchestra) passed on explicitly Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.172 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 42]: stream information have been fetched Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.196 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 42]: Metadata became available: Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.196 [1598.1598] INFO AudioStreamManager: [0xe12330]: Title: Eternity's Breath Part 2 (Album Version) Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.197 [1598.1598] INFO AudioStreamManager: [0xe12330]: Artist: Mahavishnu Orchestra Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.197 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album: Visions of the Emerald Beyond (Album Version) Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.197 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album art URL: https://static.qobuz.com/images/covers/22/67/0074644686722_600.jpg Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.213 [1598.1598] INFO HttpDownloader: [0xe12df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 05 22:38:42 volumio qobuz-connect[1598]: 20250605 22:38:42.256 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 42]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=54833&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749177522&hmac=T4xKIsnpueJq_SCPKA3pWYfub3Y Jun 05 22:38:42 volumio volumio[880]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Eternity's Breath Part 1 (Album Version)","url":"https://www.last.fm/music/Mahavishnu+Orchestra/_/Eternity%27s+Breath+Part+1+(Album+Version)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"70","playcount":"106","artist":{"name":"Mahavishnu Orchestra","mbid":"a2be74f5-0735-46f7-a295-7e41aab30029","url":"https://www.last.fm/music/Mahavishnu+Orchestra"},"userplaycount":"1","userloved":"0","toptags":""}} Jun 05 22:38:43 volumio qobuz-connect[1598]: 20250605 22:38:43.480 [1598.1598] INFO ProtocolHandler: [0xe12988]: Received SetState message: Jun 05 22:38:43 volumio qobuz-connect[1598]: 20250605 22:38:43.480 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playing state: Jun 05 22:38:43 volumio qobuz-connect[1598]: 20250605 22:38:43.480 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playback position: Jun 05 22:38:43 volumio qobuz-connect[1598]: 20250605 22:38:43.481 [1598.1598] INFO ProtocolHandler: [0xe12988]: Queue version: 4.2 Jun 05 22:38:43 volumio qobuz-connect[1598]: 20250605 22:38:43.481 [1598.1598] INFO ProtocolHandler: [0xe12988]: Current track: Jun 05 22:38:43 volumio qobuz-connect[1598]: 20250605 22:38:43.481 [1598.1598] INFO ProtocolHandler: [0xe12988]: Next track: TID: 54833, QID: 1, Context UUID: da0215da-74a2-45c0-8931-72b78ebc990f Jun 05 22:38:51 volumio qobuz-connect[1598]: 20250605 22:38:51.880 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:38:51 volumio qobuz-connect[1598]: 20250605 22:38:51.880 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:38:51 volumio qobuz-connect[1598]: 20250605 22:38:51.881 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:38:51 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:38:51 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:38:51 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:38:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:38:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:38:51 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:38:51 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:38:51 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:01 volumio qobuz-connect[1598]: 20250605 22:39:01.881 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:39:01 volumio qobuz-connect[1598]: 20250605 22:39:01.881 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:39:01 volumio qobuz-connect[1598]: 20250605 22:39:01.881 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:39:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:01 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:01 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:39:01 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:01 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:01 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:11 volumio qobuz-connect[1598]: 20250605 22:39:11.881 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:39:11 volumio qobuz-connect[1598]: 20250605 22:39:11.881 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:39:11 volumio qobuz-connect[1598]: 20250605 22:39:11.881 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:39:11 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:11 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:11 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:11 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:39:11 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:11 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:11 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:11 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:21 volumio qobuz-connect[1598]: 20250605 22:39:21.880 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:39:21 volumio qobuz-connect[1598]: 20250605 22:39:21.881 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:39:21 volumio qobuz-connect[1598]: 20250605 22:39:21.881 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:39:21 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:21 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:21 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:21 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:39:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:21 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:21 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:21 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.316 [1598.1598] INFO ProtocolHandler: [0xe12988]: Received SetState message: Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.316 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playing state: Playing Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.316 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playback position: 0 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.317 [1598.1598] INFO ProtocolHandler: [0xe12988]: Queue version: 5.1 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.317 [1598.1598] INFO ProtocolHandler: [0xe12988]: Current track: TID: 89251415, QID: 0, Context UUID: aecaa7bb-3982-4a37-914e-288a785cc947 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.317 [1598.1598] INFO ProtocolHandler: [0xe12988]: Next track: TID: 89251416, QID: 1, Context UUID: aecaa7bb-3982-4a37-914e-288a785cc947 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.317 [1598.1598] INFO MediaEngine: [0xe12248]: Stopping playback, clearing tracks Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.317 [1598.1598] INFO MediaEngine: [0xe12248]: Initiating playback Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.317 [1598.1598] INFO AudioRenderer: [0xe12468]: Stopping audio renderer Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.317 [1598.1598] INFO SampleApp: Stopping playback Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.319 [1598.1598] INFO AudioStreamManager: [0xe12330]: Clearing all streams Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.320 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 41]: Disposing of audio stream Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.320 [1598.1598] INFO ContentFetcher: [0x1096fb0]: Canceling download Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.337 [1598.1598] INFO SampleApp: Playback state changed: Stopped Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.337 [1598.1598] INFO StreamingEventReporter: [0xe12da0]: Reporting streaming ended event Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.338 [1598.1598] INFO HttpDownloader: [0xe12df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.339 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.339 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.339 [1598.1598] INFO SampleApp: [Stream 41]: Audio stream has been disposed of Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.339 [1598.1598] INFO MediaEngine: [0xe12248]: Setting current track: 89251415, initial offset: 0ms Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.339 [1598.1598] INFO AudioStreamManager: [0xe12330]: Clearing all streams Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.340 [1598.1598] INFO AudioStreamManager: [0xe12330]: New stream: 43 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.340 [1598.1598] INFO HttpDownloader: [0x16e5708]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6e7571338bd9a467ac82c6cd336da2c9&request_ts=1749173965&track_id=89251415 Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:25 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.340 [1598.1598] INFO HttpDownloader: [0x1552378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=89251415 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.341 [1598.1598] INFO HttpDownloader: [0xfb8500]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=f097f7ccc6df5fcf0c059bfee5a5c5ad&request_ts=1749173965&track_id=89251415 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.341 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 43]: Running audio stream Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.341 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.347 [1598.1598] INFO MediaEngine: [0xe12248]: Setting next track: 89251416 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.347 [1598.1598] INFO AudioStreamManager: [0xe12330]: New stream: 44 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.348 [1598.1598] INFO HttpDownloader: [0xf132f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=54daf8d51711f3d62bf3f77356bc86ba&request_ts=1749173965&track_id=89251416 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.348 [1598.1598] INFO HttpDownloader: [0xeec2d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=89251416 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.348 [1598.1598] INFO HttpDownloader: [0xe1b268]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=7679dbe84bd008d70d0daaa89208a91e&request_ts=1749173965&track_id=89251416 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.353 [1598.1598] INFO MediaEngine: [0xe12248]: Waiting for current stream to start before starting audio renderer Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:25 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.624 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 44]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=89251416&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749177565&hmac=n8h6nXTGzsK1ymQXQBfL-MdaWBs Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.650 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 43]: stream information have been fetched Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.674 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 43]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=89251415&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749177565&hmac=nzY_YD3hVr7LFnNdH11x3TeimuI Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.675 [1598.1598] INFO UrlAudioSource: [0x165ecc0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=89251415&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749177565&hmac=nzY_YD3hVr7LFnNdH11x3TeimuI Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.675 [1598.1598] INFO ContentFetcher: [0x1096fb0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=89251415&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749177565&hmac=nzY_YD3hVr7LFnNdH11x3TeimuI, offset: 0 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.676 [1598.1598] INFO AudioRenderer: [0xe12468]: Starting audio renderer, initial playback state: Playing Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.676 [1598.1598] INFO SampleApp: [Stream 43]: New audio stream (starting from 0ms) Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.680 [1598.1598] INFO SampleApp: Initiating playback, initial state: Playing Jun 05 22:39:25 volumio qobuz-connect[1598]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Jun 05 22:39:25 volumio qobuz-connect[1598]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.713 [1598.1598] INFO SampleApp: Playback state changed: Playing Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.713 [1598.1598] INFO StreamingEventReporter: [0xe12da0]: Reporting streaming started event Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.714 [1598.1598] INFO ProtocolHandler: [0xe12988]: Need to send state update Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.714 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending StateUpdated message Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.714 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:25 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.715 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending FileAudioQualityChanged message Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.716 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.717 [1598.1598] INFO QConnectMessageSender: [0x13c4e50]: Sending DeviceAudioQualityChanged message Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.718 [1598.1598] INFO QwspMessageSender: [0xfe8060]: Sending Payload message Jun 05 22:39:25 volumio qobuz-connect[1598]: 20250605 22:39:25.719 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 44]: stream information have been fetched Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:25 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:25 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 05 22:39:25 volumio volumio[880]: info: CoreStateMachine::pushState Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output update for this device Jun 05 22:39:25 volumio volumio[880]: info: MRS: Pushing multiroomSync output Jun 05 22:39:25 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Jun 05 22:39:25 volumio volumio[880]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2fUiR7QiRXgyEbmJgdrOOq4yFmu1.9ccd4fb6672625ff00da074872019019.state.track' Jun 05 22:39:25 volumio volumio[880]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2fUiR7QiRXgyEbmJgdrOOq4yFmu1.9ccd4fb6672625ff00da074872019019.state.track' Jun 05 22:39:25 volumio volumio[880]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 05 22:39:25 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 22:39:25 volumio volumio[880]: TypeError: Cannot read property 'indexOf' of undefined Jun 05 22:39:25 volumio volumio[880]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 05 22:39:25 volumio volumio[880]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 05 22:39:25 volumio volumio[880]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 05 22:39:25 volumio volumio[880]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 05 22:39:25 volumio volumio[880]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 05 22:39:25 volumio volumio[880]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 05 22:39:25 volumio volumio[880]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 05 22:39:25 volumio volumio[880]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 05 22:39:25 volumio volumio[880]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 05 22:39:25 volumio volumio[880]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 05 22:39:25 volumio volumio[880]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 05 22:39:25 volumio volumio[880]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 05 22:39:25 volumio volumio[880]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 05 22:39:25 volumio volumio[880]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 05 22:39:25 volumio volumio[880]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 05 22:39:25 volumio volumio[880]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 05 22:39:25 volumio volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.267 [1598.1598] INFO HttpDownloader: [0xe12df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.269 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 43]: Metadata became available: Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Title: At Midnight You Cry Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Artist: Black Rainbows Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album: Cosmic Ritual Supertrip Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album art URL: https://static.qobuz.com/images/covers/ea/nk/shdjf9fdankea_600.jpg Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: [Stream 44]: Metadata became available: Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Title: Universal Phase Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Artist: Black Rainbows Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album: Cosmic Ritual Supertrip Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.270 [1598.1598] INFO AudioStreamManager: [0xe12330]: Album art URL: https://static.qobuz.com/images/covers/ea/nk/shdjf9fdankea_600.jpg Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.271 [1598.1598] INFO SampleApp: [Stream 43]: Stream metadata became available: Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.271 [1598.1598] INFO SampleApp: Title: At Midnight You Cry Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.271 [1598.1598] INFO SampleApp: Artist: Black Rainbows Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.271 [1598.1598] INFO SampleApp: Album: Cosmic Ritual Supertrip Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.271 [1598.1598] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ea/nk/shdjf9fdankea_600.jpg Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.794 [1598.1598] INFO ProtocolHandler: [0xe12988]: Received SetState message: Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.794 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playing state: Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.794 [1598.1598] INFO ProtocolHandler: [0xe12988]: Playback position: Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.795 [1598.1598] INFO ProtocolHandler: [0xe12988]: Queue version: 5.2 Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.795 [1598.1598] INFO ProtocolHandler: [0xe12988]: Current track: Jun 05 22:39:26 volumio qobuz-connect[1598]: 20250605 22:39:26.795 [1598.1598] INFO ProtocolHandler: [0xe12988]: Next track: TID: 89251416, QID: 1, Context UUID: aecaa7bb-3982-4a37-914e-288a785cc947 Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.459 [1598.1598] INFO UrlAudioSource: [0x165ecc0]: Content info extracted Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.459 [1598.1598] INFO UrlAudioSource: [0x165ecc0]: Size: 22622879 Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.469 [1598.20129] INFO AudioDecoder: [0x12ef290]: Stream info: Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.469 [1598.20129] INFO AudioDecoder: [0x12ef290]: Total samples: 7825575 Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.469 [1598.20129] INFO AudioDecoder: [0x12ef290]: Sample rate: 44100 Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.470 [1598.20129] INFO AudioDecoder: [0x12ef290]: Channel count: 2 Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.470 [1598.20129] INFO AudioDecoder: [0x12ef290]: Bits per sample: 16 Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.470 [1598.20129] INFO AudioDecoder: [0x12ef290]: Duration: 177450 Jun 05 22:39:27 volumio qobuz-connect[1598]: 20250605 22:39:27.470 [1598.1598] INFO UrlAudioSource: [0x165ecc0]: Audio properties have changed Jun 05 22:39:27 volumio sudo[20131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 22:38 Jun 05 22:39:27 volumio sudo[20131]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"