-- Logs begin at Wed 2025-06-18 16:34:23 CEST, end at Wed 2025-06-18 16:48:09 CEST. -- Jun 18 16:47:05 volumio qobuz-connect[1685]: 20250618 16:47:05.909 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:47:05 volumio qobuz-connect[1685]: 20250618 16:47:05.909 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:47:05 volumio qobuz-connect[1685]: 20250618 16:47:05.909 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:47:05 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:05 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:47:05 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:47:05 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:47:05 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:47:05 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:47:05 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:47:05 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:15 volumio qobuz-connect[1685]: 20250618 16:47:15.910 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:47:15 volumio qobuz-connect[1685]: 20250618 16:47:15.910 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:47:15 volumio qobuz-connect[1685]: 20250618 16:47:15.910 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:47:15 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:15 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:47:15 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:47:15 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:47:15 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:47:15 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:47:15 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:47:15 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:25 volumio qobuz-connect[1685]: 20250618 16:47:25.909 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:47:25 volumio qobuz-connect[1685]: 20250618 16:47:25.909 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:47:25 volumio qobuz-connect[1685]: 20250618 16:47:25.909 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:47:25 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:25 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:47:25 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:47:25 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:47:25 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:47:25 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:47:25 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:47:25 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:35 volumio qobuz-connect[1685]: 20250618 16:47:35.909 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:47:35 volumio qobuz-connect[1685]: 20250618 16:47:35.909 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:47:35 volumio qobuz-connect[1685]: 20250618 16:47:35.909 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:47:35 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:35 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:47:35 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:47:35 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:47:35 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:47:35 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:47:35 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:47:35 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:45 volumio qobuz-connect[1685]: 20250618 16:47:45.909 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:47:45 volumio qobuz-connect[1685]: 20250618 16:47:45.909 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:47:45 volumio qobuz-connect[1685]: 20250618 16:47:45.909 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:47:45 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:45 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:47:45 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:47:45 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:47:45 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:47:45 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:47:45 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:47:45 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:55 volumio qobuz-connect[1685]: 20250618 16:47:55.910 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:47:55 volumio qobuz-connect[1685]: 20250618 16:47:55.910 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:47:55 volumio qobuz-connect[1685]: 20250618 16:47:55.910 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:47:55 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:47:55 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:47:55 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:47:55 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:47:55 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:47:55 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:47:55 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:47:55 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:05 volumio qobuz-connect[1685]: 20250618 16:48:05.909 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:48:05 volumio qobuz-connect[1685]: 20250618 16:48:05.909 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:48:05 volumio qobuz-connect[1685]: 20250618 16:48:05.909 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:48:05 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:05 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:05 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:05 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:48:05 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:05 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:05 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:05 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Received SetState message: Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Playing state: Playing Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Playback position: 0 Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Queue version: 3.1 Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Current track: TID: 287328239, QID: 0, Context UUID: f5d76921-72ff-433f-b51f-2829aa164605 Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Next track: TID: 287328240, QID: 1, Context UUID: f5d76921-72ff-433f-b51f-2829aa164605 Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO MediaEngine: [0x560951cbed50]: Stopping playback, clearing tracks Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO MediaEngine: [0x560951cbed50]: Initiating playback Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO AudioRenderer: [0x560951cbf150]: Stopping audio renderer Jun 18 16:48:08 volumio qobuz-connect[1685]: 20250618 16:48:08.995 [1685.1685] INFO SampleApp: Stopping playback Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.003 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Clearing all streams Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.003 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 5]: Disposing of audio stream Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.003 [1685.1685] INFO ContentFetcher: [0x560951f2e5d0]: Canceling download Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.006 [1685.1685] INFO SampleApp: Playback state changed: Stopped Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.006 [1685.1685] INFO StreamingEventReporter: [0x560951cc0240]: Reporting streaming ended event Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO HttpDownloader: [0x560951cc02d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO SampleApp: [Stream 5]: Audio stream has been disposed of Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO MediaEngine: [0x560951cbed50]: Setting current track: 287328239, initial offset: 0ms Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Clearing all streams Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: New stream: 7 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO HttpDownloader: [0x5609521a7fc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=a894e697a6136d06e4aafa7199529d16&request_ts=1750258089&track_id=287328239 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO HttpDownloader: [0x560952213fb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=287328239 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO HttpDownloader: [0x560952438c50]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=2efd487efa9e90ee23a7fec15d2528d3&request_ts=1750258089&track_id=287328239 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 7]: Running audio stream Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.007 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.008 [1685.1685] INFO MediaEngine: [0x560951cbed50]: Setting next track: 287328240 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.008 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: New stream: 8 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.009 [1685.1685] INFO HttpDownloader: [0x56095212cf20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=5962ab4a0466d48b2d0e5ec459e46473&request_ts=1750258089&track_id=287328240 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.009 [1685.1685] INFO HttpDownloader: [0x5609521bd860]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=287328240 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.009 [1685.1685] INFO HttpDownloader: [0x5609521162e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=26f7c7ce439f6d633aa84cb6e41f8cdb&request_ts=1750258089&track_id=287328240 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.010 [1685.1685] INFO MediaEngine: [0x560951cbed50]: Waiting for current stream to start before starting audio renderer Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.117 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=287328239&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1750261689&hmac=CuAeINCJS-0IPz7wEiqp9mK2BJs Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.251 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=287328240&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1750261689&hmac=wo3wYmkul18GCPZZ9C-FDaPSF8w Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.255 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 8]: stream information have been fetched Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.309 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 8]: Metadata became available: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.309 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Title: Dream Within Dream Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.309 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Artist: Stephan Micus Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.309 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Album: To the Rising Moon Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.309 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Album art URL: https://static.qobuz.com/images/covers/zb/6r/yfb987v7v6rzb_600.jpg Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.330 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 7]: stream information have been fetched Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.331 [1685.1685] INFO UrlAudioSource: [0x560952191e00]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=287328239&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1750261689&hmac=CuAeINCJS-0IPz7wEiqp9mK2BJs Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.331 [1685.1685] INFO ContentFetcher: [0x560951ef6050]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2376575&eid=287328239&fmt=7&profile=raw&app_id=174516466&cid=1651531&etsp=1750261689&hmac=CuAeINCJS-0IPz7wEiqp9mK2BJs, offset: 0 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.331 [1685.1685] INFO AudioRenderer: [0x560951cbf150]: Starting audio renderer, initial playback state: Playing Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.331 [1685.1685] INFO SampleApp: [Stream 7]: New audio stream (starting from 0ms) Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.332 [1685.1685] INFO SampleApp: Initiating playback, initial state: Playing Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.628 [1685.1685] INFO SampleApp: Playback state changed: Playing Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.628 [1685.1685] INFO StreamingEventReporter: [0x560951cc0240]: Reporting streaming started event Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.628 [1685.1685] INFO HttpDownloader: [0x560951cc02d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.629 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Need to send state update Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.629 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending StateUpdated message Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.629 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending FileAudioQualityChanged message Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Received SetState message: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Playing state: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Playback position: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Queue version: 3.2 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Current track: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO ProtocolHandler: [0x560951cbfaa0]: Next track: TID: 287328240, QID: 1, Context UUID: f5d76921-72ff-433f-b51f-2829aa164605 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO QConnectMessageSender: [0x560951ed1830]: Sending DeviceAudioQualityChanged message Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.630 [1685.1685] INFO QwspMessageSender: [0x560951ed1810]: Sending Payload message Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: [Stream 7]: Metadata became available: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Title: To the Rising Sun Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Artist: Stephan Micus Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Album: To the Rising Moon Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO AudioStreamManager: [0x560951cbeef0]: Album art URL: https://static.qobuz.com/images/covers/zb/6r/yfb987v7v6rzb_600.jpg Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO SampleApp: [Stream 7]: Stream metadata became available: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO SampleApp: Title: To the Rising Sun Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO SampleApp: Artist: Stephan Micus Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO SampleApp: Album: To the Rising Moon Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.631 [1685.1685] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/zb/6r/yfb987v7v6rzb_600.jpg Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreStateMachine::pushState Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output update for this device Jun 18 16:48:09 volumio volumio[1040]: info: MRS: Pushing multiroomSync output Jun 18 16:48:09 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 18 16:48:09 volumio volumio[1040]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 18 16:48:09 volumio volumio[1040]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 16:48:09 volumio volumio[1040]: TypeError: Cannot read property 'indexOf' of undefined Jun 18 16:48:09 volumio volumio[1040]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 18 16:48:09 volumio volumio[1040]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 18 16:48:09 volumio volumio[1040]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 18 16:48:09 volumio volumio[1040]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 18 16:48:09 volumio volumio[1040]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 18 16:48:09 volumio volumio[1040]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 18 16:48:09 volumio volumio[1040]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 18 16:48:09 volumio volumio[1040]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 18 16:48:09 volumio volumio[1040]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 18 16:48:09 volumio volumio[1040]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 18 16:48:09 volumio volumio[1040]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 18 16:48:09 volumio volumio[1040]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 18 16:48:09 volumio volumio[1040]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 18 16:48:09 volumio volumio[1040]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 18 16:48:09 volumio volumio[1040]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 18 16:48:09 volumio volumio[1040]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 18 16:48:09 volumio volumio[1040]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.813 [1685.1685] INFO UrlAudioSource: [0x560952191e00]: Content info extracted Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.813 [1685.1685] INFO UrlAudioSource: [0x560952191e00]: Size: 41835241 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.817 [1685.3576] INFO AudioDecoder: [0x560951dc0b70]: Stream info: Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.817 [1685.3576] INFO AudioDecoder: [0x560951dc0b70]: Total samples: 10631168 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.817 [1685.3576] INFO AudioDecoder: [0x560951dc0b70]: Sample rate: 44100 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.817 [1685.3576] INFO AudioDecoder: [0x560951dc0b70]: Channel count: 2 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.817 [1685.3576] INFO AudioDecoder: [0x560951dc0b70]: Bits per sample: 24 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.817 [1685.3576] INFO AudioDecoder: [0x560951dc0b70]: Duration: 241069 Jun 18 16:48:09 volumio qobuz-connect[1685]: 20250618 16:48:09.819 [1685.1685] INFO UrlAudioSource: [0x560952191e00]: Audio properties have changed Jun 18 16:48:09 volumio sudo[3581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 16:47 Jun 18 16:48:09 volumio sudo[3581]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"