-- Logs begin at Tue 2025-05-20 18:57:18 CEST, end at Tue 2025-05-20 19:14:30 CEST. -- May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Received SetState message: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playing state: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playback position: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Queue version: 1.2 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Current track: TID: 315252222, QID: 1, Context UUID: 878646c4-0f30-4174-9fa3-fcb1c7a1f21b May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Next track: TID: 315252223, QID: 2, Context UUID: 878646c4-0f30-4174-9fa3-fcb1c7a1f21b May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Stopping playback, clearing tracks May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Initiating playback May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Stopping audio renderer May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.120 [1753.1753] INFO SampleApp: Stopping playback May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.127 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Clearing all streams May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.128 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 16]: Disposing of audio stream May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.128 [1753.1753] INFO ContentFetcher: [0x55cc914969b0]: Canceling download May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO SampleApp: Playback state changed: Stopped May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming ended event May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO SampleApp: [Stream 16]: Audio stream has been disposed of May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Setting current track: 315252222, initial offset: 0ms May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Clearing all streams May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.130 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: New stream: 18 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.131 [1753.1753] INFO HttpDownloader: [0x55cc91028f20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=2590928ea50a730062110f0fb4799f10&request_ts=1747761180&track_id=315252222 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.131 [1753.1753] INFO HttpDownloader: [0x55cc9123e620]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=315252222 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.131 [1753.1753] INFO HttpDownloader: [0x55cc90f252a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=677a4a541887b8ec7fe059c591045a1a&request_ts=1747761180&track_id=315252222 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.131 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 18]: Running audio stream May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.131 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:00 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.132 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Setting next track: 315252223 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.132 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: New stream: 19 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.132 [1753.1753] INFO HttpDownloader: [0x55cc91072ef0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=fe245799279744b8d0303f7eaacf0e88&request_ts=1747761180&track_id=315252223 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.132 [1753.1753] INFO HttpDownloader: [0x55cc91211a20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=315252223 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.132 [1753.1753] INFO HttpDownloader: [0x55cc91162410]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=07891b4203acd7bef808e53f2ae8a529&request_ts=1747761180&track_id=315252223 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.133 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Waiting for current stream to start before starting audio renderer May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:00 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.197 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Received SetState message: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.197 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playing state: Playing May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.197 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playback position: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.197 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Queue version: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.197 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Current track: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.197 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Next track: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.197 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Resuming playback May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.225 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 18]: Metadata became available: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.225 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Title: That Old Feeling May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.225 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Artist: Eloise May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.225 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album: Chet Baker Re:imagined May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.225 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album art URL: https://static.qobuz.com/images/covers/ub/05/oeb5fnhde05ub_600.jpg May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.228 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 19]: Metadata became available: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.228 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Title: I’m Old Fashioned May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.228 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Artist: Sarah Kang May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.228 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album: Chet Baker Re:imagined May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.229 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album art URL: https://static.qobuz.com/images/covers/ub/05/oeb5fnhde05ub_600.jpg May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.271 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 18]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252222&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764780&hmac=Cp693qW6FEWIJofRvqoZ1nxV1sY May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 18]: stream information have been fetched May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO UrlAudioSource: [0x55cc9155b0f0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252222&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764780&hmac=Cp693qW6FEWIJofRvqoZ1nxV1sY May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO ContentFetcher: [0x55cc91570400]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252222&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764780&hmac=Cp693qW6FEWIJofRvqoZ1nxV1sY, offset: 0 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Starting audio renderer, initial playback state: Playing May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO SampleApp: [Stream 18]: New audio stream (starting from 0ms) May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO SampleApp: [Stream 18]: Stream metadata became available: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO SampleApp: Title: That Old Feeling May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO SampleApp: Artist: Eloise May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO SampleApp: Album: Chet Baker Re:imagined May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.319 [1753.1753] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ub/05/oeb5fnhde05ub_600.jpg May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: QobuzConnect: alread currrent service, send playback confirmation May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.321 [1753.1753] INFO SampleApp: Initiating playback, initial state: Playing May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.472 [1753.1753] INFO SampleApp: Playback state changed: Playing May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.472 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming started event May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.472 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.472 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.472 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.472 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.473 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending FileAudioQualityChanged message May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.473 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.474 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 19]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252223&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764780&hmac=UIlVWMrlCbuDL9pVZQytQeZ3x44 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.474 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 19]: stream information have been fetched May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:00 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:00 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:00 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:00 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:00 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:00 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:00 volumio volumio[1059]: info: [LastFM] Current track has sufficient metadata: title (That Old Feeling) and artist (Eloise) passed on explicitly May 20 19:13:00 volumio volumio[1059]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.602 [1753.1753] INFO UrlAudioSource: [0x55cc9155b0f0]: Content info extracted May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.1753] INFO UrlAudioSource: [0x55cc9155b0f0]: Size: 28191920 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.4011] INFO AudioDecoder: [0x55cc91790720]: Stream info: May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.4011] INFO AudioDecoder: [0x55cc91790720]: Total samples: 7385088 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.4011] INFO AudioDecoder: [0x55cc91790720]: Sample rate: 48000 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.4011] INFO AudioDecoder: [0x55cc91790720]: Channel count: 2 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.4011] INFO AudioDecoder: [0x55cc91790720]: Bits per sample: 24 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.4011] INFO AudioDecoder: [0x55cc91790720]: Duration: 153856 May 20 19:13:00 volumio qobuz-connect[1753]: 20250520 19:13:00.603 [1753.1753] INFO UrlAudioSource: [0x55cc9155b0f0]: Audio properties have changed May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Received SetState message: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playing state: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playback position: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Queue version: 1.2 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Current track: TID: 315252223, QID: 2, Context UUID: 878646c4-0f30-4174-9fa3-fcb1c7a1f21b May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Next track: TID: 315252224, QID: 3, Context UUID: 878646c4-0f30-4174-9fa3-fcb1c7a1f21b May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Stopping playback, clearing tracks May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Initiating playback May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Stopping audio renderer May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.813 [1753.1753] INFO SampleApp: Stopping playback May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.819 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Clearing all streams May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.820 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 18]: Disposing of audio stream May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.820 [1753.1753] INFO ContentFetcher: [0x55cc91570400]: Canceling download May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO SampleApp: Playback state changed: Stopped May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming ended event May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO SampleApp: [Stream 18]: Audio stream has been disposed of May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Setting current track: 315252223, initial offset: 0ms May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Clearing all streams May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: New stream: 20 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.822 [1753.1753] INFO HttpDownloader: [0x55cc91558160]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=036ad581bcce92027a47d0ac0aa3e111&request_ts=1747761184&track_id=315252223 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.823 [1753.1753] INFO HttpDownloader: [0x55cc9103c110]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=315252223 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.823 [1753.1753] INFO HttpDownloader: [0x55cc910238c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=2733c2121c3d16a700877bc775332716&request_ts=1747761184&track_id=315252223 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.823 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 20]: Running audio stream May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.823 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:04 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.824 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Setting next track: 315252224 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.824 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: New stream: 21 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.824 [1753.1753] INFO HttpDownloader: [0x55cc91161180]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=2cfc0364417fccdfb8bf6b23e809f924&request_ts=1747761184&track_id=315252224 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.824 [1753.1753] INFO HttpDownloader: [0x55cc91014200]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=315252224 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.824 [1753.1753] INFO HttpDownloader: [0x55cc91246200]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=acef4224c9752c3e521720a48ac93bfc&request_ts=1747761184&track_id=315252224 May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.825 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Waiting for current stream to start before starting audio renderer May 20 19:13:04 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:04 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:04 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:04 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:04 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:04 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.914 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 20]: Metadata became available: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.914 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Title: I’m Old Fashioned May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.914 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Artist: Sarah Kang May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.914 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album: Chet Baker Re:imagined May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.914 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album art URL: https://static.qobuz.com/images/covers/ub/05/oeb5fnhde05ub_600.jpg May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.919 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Received SetState message: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.919 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playing state: Playing May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.919 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playback position: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.919 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Queue version: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.919 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Current track: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.919 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Next track: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.919 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Resuming playback May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.940 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 21]: Metadata became available: May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.940 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Title: I Get Along Without You Very Well May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.940 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Artist: Hohnen Ford May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.940 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album: Chet Baker Re:imagined May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.940 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album art URL: https://static.qobuz.com/images/covers/ub/05/oeb5fnhde05ub_600.jpg May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.947 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 20]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252223&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764784&hmac=BHRs6noeY88iRiejP91TQ3g5KfY May 20 19:13:04 volumio qobuz-connect[1753]: 20250520 19:13:04.983 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 21]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252224&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764784&hmac=WEZ08bMed9ZrL_aZpgUCwSsTJfw May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 20]: stream information have been fetched May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO UrlAudioSource: [0x55cc91024160]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252223&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764784&hmac=BHRs6noeY88iRiejP91TQ3g5KfY May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO ContentFetcher: [0x55cc91588780]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=315252223&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764784&hmac=BHRs6noeY88iRiejP91TQ3g5KfY, offset: 0 May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Starting audio renderer, initial playback state: Playing May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO SampleApp: [Stream 20]: New audio stream (starting from 0ms) May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO SampleApp: [Stream 20]: Stream metadata became available: May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO SampleApp: Title: I’m Old Fashioned May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO SampleApp: Artist: Sarah Kang May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO SampleApp: Album: Chet Baker Re:imagined May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.012 [1753.1753] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ub/05/oeb5fnhde05ub_600.jpg May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: QobuzConnect: alread currrent service, send playback confirmation May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.014 [1753.1753] INFO SampleApp: Initiating playback, initial state: Playing May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.190 [1753.1753] INFO SampleApp: Playback state changed: Playing May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.190 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming started event May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.190 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.190 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.190 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.190 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending FileAudioQualityChanged message May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.190 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.191 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.192 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 21]: stream information have been fetched May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:05 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:05 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:05 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:05 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:05 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:05 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:05 volumio volumio[1059]: info: [LastFM] Current track has sufficient metadata: title (I’m Old Fashioned) and artist (Sarah Kang) passed on explicitly May 20 19:13:05 volumio volumio[1059]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.274 [1753.1753] INFO UrlAudioSource: [0x55cc91024160]: Content info extracted May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.274 [1753.1753] INFO UrlAudioSource: [0x55cc91024160]: Size: 40794404 May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.277 [1753.4015] INFO AudioDecoder: [0x55cc915a45e0]: Stream info: May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.278 [1753.4015] INFO AudioDecoder: [0x55cc915a45e0]: Total samples: 10665600 May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.278 [1753.4015] INFO AudioDecoder: [0x55cc915a45e0]: Sample rate: 48000 May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.278 [1753.4015] INFO AudioDecoder: [0x55cc915a45e0]: Channel count: 2 May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.278 [1753.4015] INFO AudioDecoder: [0x55cc915a45e0]: Bits per sample: 24 May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.278 [1753.4015] INFO AudioDecoder: [0x55cc915a45e0]: Duration: 222200 May 20 19:13:05 volumio qobuz-connect[1753]: 20250520 19:13:05.278 [1753.1753] INFO UrlAudioSource: [0x55cc91024160]: Audio properties have changed May 20 19:13:15 volumio qobuz-connect[1753]: 20250520 19:13:15.190 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:15 volumio qobuz-connect[1753]: 20250520 19:13:15.190 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:15 volumio qobuz-connect[1753]: 20250520 19:13:15.190 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:15 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:15 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:15 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:15 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:15 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:15 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:15 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:15 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:25 volumio qobuz-connect[1753]: 20250520 19:13:25.190 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:25 volumio qobuz-connect[1753]: 20250520 19:13:25.190 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:25 volumio qobuz-connect[1753]: 20250520 19:13:25.190 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:25 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:25 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:25 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:25 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:25 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:25 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:25 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:25 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Received SetState message: May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playing state: Paused May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playback position: May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Queue version: May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Current track: May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Next track: May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Pausing playback May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Pausing audio renderer May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO SampleApp: Pausing playback May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO SampleApp: Playback state changed: Paused May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming ended event May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:26 volumio qobuz-connect[1753]: 20250520 19:13:26.681 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:26 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:26 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:26 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:26 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:26 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:26 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:26 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:26 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:26 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:26 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Received SetState message: May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playing state: Playing May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playback position: May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Queue version: May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Current track: May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Next track: May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Resuming playback May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Resuming audio renderer May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.506 [1753.1753] INFO SampleApp: Resuming playback May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.511 [1753.1753] INFO SampleApp: Playback state changed: Playing May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.511 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming started event May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.511 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.511 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.511 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:33 volumio qobuz-connect[1753]: 20250520 19:13:33.511 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:33 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:33 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:33 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:33 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:33 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:33 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:43 volumio qobuz-connect[1753]: 20250520 19:13:43.510 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:43 volumio qobuz-connect[1753]: 20250520 19:13:43.511 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:43 volumio qobuz-connect[1753]: 20250520 19:13:43.511 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:43 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:43 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:43 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:43 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:43 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:43 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:43 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:53 volumio qobuz-connect[1753]: 20250520 19:13:53.510 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:13:53 volumio qobuz-connect[1753]: 20250520 19:13:53.511 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:13:53 volumio qobuz-connect[1753]: 20250520 19:13:53.511 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:13:53 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:13:53 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:13:53 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:13:53 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:13:53 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:13:53 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:13:53 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:13:53 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:03 volumio qobuz-connect[1753]: 20250520 19:14:03.510 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:14:03 volumio qobuz-connect[1753]: 20250520 19:14:03.510 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:14:03 volumio qobuz-connect[1753]: 20250520 19:14:03.511 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:14:03 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:03 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:03 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:03 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:03 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:03 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:03 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:03 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:13 volumio qobuz-connect[1753]: 20250520 19:14:13.510 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:14:13 volumio qobuz-connect[1753]: 20250520 19:14:13.511 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:14:13 volumio qobuz-connect[1753]: 20250520 19:14:13.511 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:14:13 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:13 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:13 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:13 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:13 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:13 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:13 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:13 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:23 volumio qobuz-connect[1753]: 20250520 19:14:23.510 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:14:23 volumio qobuz-connect[1753]: 20250520 19:14:23.510 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:14:23 volumio qobuz-connect[1753]: 20250520 19:14:23.510 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:14:23 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:23 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:23 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:23 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:23 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:23 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:23 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:23 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Received SetState message: May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playing state: Playing May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Playback position: 0 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Queue version: 2.1 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Current track: TID: 317852508, QID: 0, Context UUID: 16115c35-6e40-4aa7-9a2e-b108d94205e2 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Next track: TID: 317852509, QID: 1, Context UUID: 16115c35-6e40-4aa7-9a2e-b108d94205e2 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Stopping playback, clearing tracks May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Initiating playback May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Stopping audio renderer May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.232 [1753.1753] INFO SampleApp: Stopping playback May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.239 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Clearing all streams May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.240 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 20]: Disposing of audio stream May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.240 [1753.1753] INFO ContentFetcher: [0x55cc91588780]: Canceling download May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO SampleApp: Playback state changed: Stopped May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming ended event May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO SampleApp: [Stream 20]: Audio stream has been disposed of May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Setting current track: 317852508, initial offset: 0ms May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Clearing all streams May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: New stream: 22 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO HttpDownloader: [0x55cc910289a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=0d93dd25eae2368cd21a32c7216ce0ca&request_ts=1747761269&track_id=317852508 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.242 [1753.1753] INFO HttpDownloader: [0x55cc9101c3b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=317852508 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.243 [1753.1753] INFO HttpDownloader: [0x55cc90ffba80]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ba3f8e7ed9b855a18722c793748ffabb&request_ts=1747761269&track_id=317852508 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.243 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 22]: Running audio stream May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.243 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:29 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:29 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.256 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Setting next track: 317852509 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.256 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: New stream: 23 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.256 [1753.1753] INFO HttpDownloader: [0x55cc911770b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=c642cbfe24740e2e3a3eaeab62e52a42&request_ts=1747761269&track_id=317852509 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.256 [1753.1753] INFO HttpDownloader: [0x55cc91246200]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=317852509 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.256 [1753.1753] INFO HttpDownloader: [0x55cc90e933a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=08e03e73079c3a5ed1d6fcc1f9945474&request_ts=1747761269&track_id=317852509 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.257 [1753.1753] INFO MediaEngine: [0x55cc90e41d50]: Waiting for current stream to start before starting audio renderer May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.453 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 23]: stream information have been fetched May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.473 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 22]: stream information have been fetched May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.500 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 22]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=317852508&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764869&hmac=UIdKw5MC-Pe0spfs1HLhFTg2RPA May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.500 [1753.1753] INFO UrlAudioSource: [0x55cc90ef9c30]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=317852508&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764869&hmac=UIdKw5MC-Pe0spfs1HLhFTg2RPA May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.500 [1753.1753] INFO ContentFetcher: [0x55cc90eee150]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=317852508&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764869&hmac=UIdKw5MC-Pe0spfs1HLhFTg2RPA, offset: 0 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.501 [1753.1753] INFO AudioRenderer: [0x55cc90e42150]: Starting audio renderer, initial playback state: Playing May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.501 [1753.1753] INFO SampleApp: [Stream 22]: New audio stream (starting from 0ms) May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: QobuzConnect: alread currrent service, send playback confirmation May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.502 [1753.1753] INFO SampleApp: Initiating playback, initial state: Playing May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO SampleApp: Playback state changed: Playing May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO StreamingEventReporter: [0x55cc90e43240]: Reporting streaming started event May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO HttpDownloader: [0x55cc90e432d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO ProtocolHandler: [0x55cc90e42aa0]: Need to send state update May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending StateUpdated message May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 22]: Metadata became available: May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Title: WHO WANT IT May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Artist: Rico Nasty May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.797 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album: LETHAL May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.798 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album art URL: https://static.qobuz.com/images/covers/1b/4f/clirfuvpm4f1b_600.jpg May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.798 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.798 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending FileAudioQualityChanged message May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO SampleApp: [Stream 22]: Stream metadata became available: May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO SampleApp: Title: WHO WANT IT May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO SampleApp: Artist: Rico Nasty May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO SampleApp: Album: LETHAL May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/1b/4f/clirfuvpm4f1b_600.jpg May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO QConnectMessageSender: [0x55cc9128baf0]: Sending DeviceAudioQualityChanged message May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:29 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO QwspMessageSender: [0x55cc90ea6870]: Sending Payload message May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.799 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 23]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=317852509&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1747764869&hmac=SieR2bLzxU5PRELq-2Tj77uvkB8 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.800 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: [Stream 23]: Metadata became available: May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.800 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Title: TEETHSUCKER (YEA3x) May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.800 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Artist: Rico Nasty May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.800 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album: LETHAL May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.800 [1753.1753] INFO AudioStreamManager: [0x55cc90e41ef0]: Album art URL: https://static.qobuz.com/images/covers/1b/4f/clirfuvpm4f1b_600.jpg May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:29 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:29 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:29 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState May 20 19:14:29 volumio volumio[1059]: info: CoreStateMachine::pushState May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output update for this device May 20 19:14:29 volumio volumio[1059]: info: MRS: Pushing multiroomSync output May 20 19:14:29 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState May 20 19:14:29 volumio volumio[1059]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 20 19:14:29 volumio volumio[1059]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 19:14:29 volumio volumio[1059]: TypeError: Cannot read property 'indexOf' of undefined May 20 19:14:29 volumio volumio[1059]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 20 19:14:29 volumio volumio[1059]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 20 19:14:29 volumio volumio[1059]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 20 19:14:29 volumio volumio[1059]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 20 19:14:29 volumio volumio[1059]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 20 19:14:29 volumio volumio[1059]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 20 19:14:29 volumio volumio[1059]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 20 19:14:29 volumio volumio[1059]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 20 19:14:29 volumio volumio[1059]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 20 19:14:29 volumio volumio[1059]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 20 19:14:29 volumio volumio[1059]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 20 19:14:29 volumio volumio[1059]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 20 19:14:29 volumio volumio[1059]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 20 19:14:29 volumio volumio[1059]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 20 19:14:29 volumio volumio[1059]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 20 19:14:29 volumio volumio[1059]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 20 19:14:29 volumio volumio[1059]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.921 [1753.1753] INFO UrlAudioSource: [0x55cc90ef9c30]: Content info extracted May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.921 [1753.1753] INFO UrlAudioSource: [0x55cc90ef9c30]: Size: 23523531 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.924 [1753.4230] INFO AudioDecoder: [0x55cc90fc5f20]: Stream info: May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.924 [1753.4230] INFO AudioDecoder: [0x55cc90fc5f20]: Total samples: 4753980 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.924 [1753.4230] INFO AudioDecoder: [0x55cc90fc5f20]: Sample rate: 44100 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.924 [1753.4230] INFO AudioDecoder: [0x55cc90fc5f20]: Channel count: 2 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.924 [1753.4230] INFO AudioDecoder: [0x55cc90fc5f20]: Bits per sample: 24 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.924 [1753.4230] INFO AudioDecoder: [0x55cc90fc5f20]: Duration: 107800 May 20 19:14:29 volumio qobuz-connect[1753]: 20250520 19:14:29.925 [1753.1753] INFO UrlAudioSource: [0x55cc90ef9c30]: Audio properties have changed May 20 19:14:30 volumio sudo[4246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 19:13 May 20 19:14:30 volumio sudo[4246]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"