-- Logs begin at Sun 2025-06-29 21:15:27 CEST, end at Tue 2025-07-01 11:57:46 CEST. -- Jul 01 11:56:01 valou-volumio qobuz-connect[1679]: 20250701 11:56:01.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:56:01 valou-volumio qobuz-connect[1679]: 20250701 11:56:01.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:56:01 valou-volumio qobuz-connect[1679]: 20250701 11:56:01.842 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:56:01 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:01 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:56:01 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:56:01 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:56:01 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:56:01 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:56:01 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:56:01 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:01 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:56:01 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:56:11 valou-volumio qobuz-connect[1679]: 20250701 11:56:11.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:56:11 valou-volumio qobuz-connect[1679]: 20250701 11:56:11.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:56:11 valou-volumio qobuz-connect[1679]: 20250701 11:56:11.842 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:56:11 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:11 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:56:11 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:56:11 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:56:11 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:56:11 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:56:11 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:56:11 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:11 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:56:11 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:56:21 valou-volumio qobuz-connect[1679]: 20250701 11:56:21.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:56:21 valou-volumio qobuz-connect[1679]: 20250701 11:56:21.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:56:21 valou-volumio qobuz-connect[1679]: 20250701 11:56:21.842 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:56:21 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:21 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:56:21 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:56:21 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:56:21 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:56:21 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:56:21 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:56:21 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:21 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:56:21 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:56:28 valou-volumio volumio[1187]: error: [LastFM] plugin is not authenticated, please retry Jul 01 11:56:31 valou-volumio qobuz-connect[1679]: 20250701 11:56:31.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:56:31 valou-volumio qobuz-connect[1679]: 20250701 11:56:31.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:56:31 valou-volumio qobuz-connect[1679]: 20250701 11:56:31.842 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:56:31 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:31 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:56:31 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:56:31 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:56:31 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:56:31 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:56:31 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:56:31 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:31 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:56:31 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:56:41 valou-volumio qobuz-connect[1679]: 20250701 11:56:41.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:56:41 valou-volumio qobuz-connect[1679]: 20250701 11:56:41.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:56:41 valou-volumio qobuz-connect[1679]: 20250701 11:56:41.842 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:56:41 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:41 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:56:41 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:56:41 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:56:41 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:56:41 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:56:41 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:56:41 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:41 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:56:41 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:56:51 valou-volumio qobuz-connect[1679]: 20250701 11:56:51.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:56:51 valou-volumio qobuz-connect[1679]: 20250701 11:56:51.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:56:51 valou-volumio qobuz-connect[1679]: 20250701 11:56:51.842 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:56:51 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:51 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:56:51 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:56:51 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:56:51 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:56:51 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:56:51 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:56:51 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:56:51 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:56:51 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:01 valou-volumio qobuz-connect[1679]: 20250701 11:57:01.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:01 valou-volumio qobuz-connect[1679]: 20250701 11:57:01.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:01 valou-volumio qobuz-connect[1679]: 20250701 11:57:01.842 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:01 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:01 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:01 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:01 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:01 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:01 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:01 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:01 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:01 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:01 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.134 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 108]: Running audio stream Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.134 [1679.1679] INFO UrlAudioSource: [0x1ebe5e0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=2842274&fmt=6&profile=raw&app_id=174516466&cid=2788545&etsp=1751367251&hmac=uiD3Eykkk93PAQ9lWjMnNnfLA8I Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.135 [1679.1679] INFO ContentFetcher: [0x2096e60]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=2842274&fmt=6&profile=raw&app_id=174516466&cid=2788545&etsp=1751367251&hmac=uiD3Eykkk93PAQ9lWjMnNnfLA8I, offset: 0 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.140 [1679.1679] INFO SampleApp: [Stream 108]: New audio stream (starting from 0ms) Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.140 [1679.1679] INFO SampleApp: [Stream 108]: Stream metadata became available: Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.140 [1679.1679] INFO SampleApp: Title: The Grand Duel (Parte Prima Album Version) Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.140 [1679.1679] INFO SampleApp: Artist: Luis Bacalov Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.140 [1679.1679] INFO SampleApp: Album: Bande Originale du Film "Kill Bill Vol. 1" (2003) (Parte Prima Album Version) Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.140 [1679.1679] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/68/70/0093624857068_600.jpg Jul 01 11:57:03 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.258 [1679.1679] INFO UrlAudioSource: [0x1ebe5e0]: Content info extracted Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.258 [1679.1679] INFO UrlAudioSource: [0x1ebe5e0]: Size: 18939893 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.262 [1679.26281] INFO AudioDecoder: [0x1b0a708]: Stream info: Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.263 [1679.26281] INFO AudioDecoder: [0x1b0a708]: Total samples: 9030504 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.263 [1679.26281] INFO AudioDecoder: [0x1b0a708]: Sample rate: 44100 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.263 [1679.26281] INFO AudioDecoder: [0x1b0a708]: Channel count: 2 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.263 [1679.26281] INFO AudioDecoder: [0x1b0a708]: Bits per sample: 16 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.263 [1679.26281] INFO AudioDecoder: [0x1b0a708]: Duration: 204773 Jul 01 11:57:03 valou-volumio qobuz-connect[1679]: 20250701 11:57:03.263 [1679.1679] INFO UrlAudioSource: [0x1ebe5e0]: Audio properties have changed Jul 01 11:57:10 valou-volumio qobuz-connect[1679]: 20250701 11:57:10.009 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 107]: Audio stream has finished Jul 01 11:57:10 valou-volumio qobuz-connect[1679]: 20250701 11:57:10.009 [1679.1679] INFO SampleApp: [Stream 107]: Audio stream finished Jul 01 11:57:10 valou-volumio qobuz-connect[1679]: 20250701 11:57:10.528 [1679.1679] INFO SampleApp: Playback is nearly finished Jul 01 11:57:11 valou-volumio qobuz-connect[1679]: 20250701 11:57:11.842 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:11 valou-volumio qobuz-connect[1679]: 20250701 11:57:11.842 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:11 valou-volumio qobuz-connect[1679]: 20250701 11:57:11.843 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:11 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:11 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:11 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:11 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:11 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:11 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:11 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:11 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:11 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:11 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO SampleApp: Playback has finished Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 107]: Disposing of audio stream Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO ContentFetcher: [0x22f25b0]: Canceling download Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO SampleApp: [Stream 107]: Audio stream has been disposed of Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO StreamingEventReporter: [0x1a68150]: Reporting streaming ended event Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO HttpDownloader: [0x1a681a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.527 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.528 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.528 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.528 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.528 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending FileAudioQualityChanged message Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.529 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.529 [1679.1679] INFO StreamingEventReporter: [0x1a68150]: Reporting streaming started event Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:13 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:13 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:13 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:13 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:13 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:13 valou-volumio volumio[1187]: info: [LastFM] Current track has sufficient metadata: title (The Grand Duel (Parte Prima Album Version)) and artist (Luis Bacalov) passed on explicitly Jul 01 11:57:13 valou-volumio volumio[1187]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jul 01 11:57:13 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:13 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:13 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:13 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:13 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:13 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Received SetState message: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Playing state: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Playback position: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Queue version: 4.1 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Current track: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Next track: TID: 2842275, QID: 3, Context UUID: 777eef31-f704-4d4f-a364-9efeaa7b0b53 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO MediaEngine: [0x1a675f8]: Setting next track: 2842275 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: New stream: 109 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO HttpDownloader: [0x2377098]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6ec75a74beccd584cd4178677d7e1f40&request_ts=1751363833&track_id=2842275 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO HttpDownloader: [0x2377990]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=2842275 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO HttpDownloader: [0x1f7c330]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a753aacb435dd34db57d38b9e4be1490&request_ts=1751363833&track_id=2842275 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Received SetState message: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Playing state: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Playback position: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Queue version: 4.1 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Current track: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.596 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Next track: TID: 2842275, QID: 3, Context UUID: 777eef31-f704-4d4f-a364-9efeaa7b0b53 Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.785 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 109]: Metadata became available: Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.785 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Title: Twisted Nerve (Album Version) Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.785 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Artist: Bernard Herrmann Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.785 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Album: Bande Originale du Film "Kill Bill Vol. 1" (2003) (Album Version) Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.785 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Album art URL: https://static.qobuz.com/images/covers/68/70/0093624857068_600.jpg Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.822 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 109]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=2842275&fmt=6&profile=raw&app_id=174516466&cid=2788545&etsp=1751367433&hmac=v3e4eDCvZVJ_5udWcsymj5H8OSs Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.862 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 109]: stream information have been fetched Jul 01 11:57:13 valou-volumio qobuz-connect[1679]: 20250701 11:57:13.877 [1679.1679] INFO HttpDownloader: [0x1a681a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jul 01 11:57:23 valou-volumio qobuz-connect[1679]: 20250701 11:57:23.528 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:23 valou-volumio qobuz-connect[1679]: 20250701 11:57:23.528 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:23 valou-volumio qobuz-connect[1679]: 20250701 11:57:23.528 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:23 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:23 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:23 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:23 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:23 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:23 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:23 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:23 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:23 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:23 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:33 valou-volumio qobuz-connect[1679]: 20250701 11:57:33.528 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:33 valou-volumio qobuz-connect[1679]: 20250701 11:57:33.528 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:33 valou-volumio qobuz-connect[1679]: 20250701 11:57:33.528 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:33 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:33 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:33 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:33 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:33 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:33 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:33 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:33 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:33 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:33 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:43 valou-volumio qobuz-connect[1679]: 20250701 11:57:43.528 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:43 valou-volumio qobuz-connect[1679]: 20250701 11:57:43.528 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:43 valou-volumio qobuz-connect[1679]: 20250701 11:57:43.528 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:43 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:43 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:43 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:43 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:43 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:43 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:43 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:43 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:43 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:43 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Received SetState message: Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Playing state: Playing Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Playback position: 0 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Queue version: 5.1 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Current track: TID: 2842396, QID: 0, Context UUID: 9baf09c1-f18d-4162-95ad-613d54034436 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Next track: TID: 2842397, QID: 1, Context UUID: 9baf09c1-f18d-4162-95ad-613d54034436 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO MediaEngine: [0x1a675f8]: Stopping playback, clearing tracks Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO MediaEngine: [0x1a675f8]: Initiating playback Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO AudioRenderer: [0x1a67818]: Stopping audio renderer Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.581 [1679.1679] INFO SampleApp: Stopping playback Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.583 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Clearing all streams Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.583 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 108]: Disposing of audio stream Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.583 [1679.1679] INFO ContentFetcher: [0x2096e60]: Canceling download Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO SampleApp: Playback state changed: Stopped Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO StreamingEventReporter: [0x1a68150]: Reporting streaming ended event Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO HttpDownloader: [0x1a681a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO SampleApp: [Stream 108]: Audio stream has been disposed of Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO MediaEngine: [0x1a675f8]: Setting current track: 2842396, initial offset: 0ms Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Clearing all streams Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.587 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: New stream: 110 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.588 [1679.1679] INFO HttpDownloader: [0x1d3e660]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e793a09f8e8515e899a5356a55c57ce8&request_ts=1751363865&track_id=2842396 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.588 [1679.1679] INFO HttpDownloader: [0x22a68d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=2842396 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.588 [1679.1679] INFO HttpDownloader: [0x1dc4b58]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=19f757945a62f545063f041370c46323&request_ts=1751363865&track_id=2842396 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.588 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 110]: Running audio stream Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.588 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.589 [1679.1679] INFO MediaEngine: [0x1a675f8]: Setting next track: 2842397 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.589 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: New stream: 111 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.589 [1679.1679] INFO HttpDownloader: [0x1ceca68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=bc20d42d0d8e238ae64ce26d65e6a063&request_ts=1751363865&track_id=2842397 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.589 [1679.1679] INFO HttpDownloader: [0x253c2e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=2842397 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.589 [1679.1679] INFO HttpDownloader: [0x22ad040]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=7b1ceb5f78c1def9aadab5cb20e7989f&request_ts=1751363865&track_id=2842397 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.590 [1679.1679] INFO MediaEngine: [0x1a675f8]: Waiting for current stream to start before starting audio renderer Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] CurState: pause PrevState: play Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] CurState: pause PrevState: play Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 01 11:57:45 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:45 valou-volumio volumio[1187]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.720 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 110]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=2842396&fmt=6&profile=raw&app_id=174516466&cid=2788545&etsp=1751367465&hmac=lvbxIIbgiXEkIXqoALsvCvmvW0E Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.778 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 111]: Metadata became available: Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.778 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Title: Goodnight Moon (Album Version) Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.778 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Artist: Shivaree Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.778 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Album: Kill Bill Vol. 2 Original Soundtrack (Album Version) Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.778 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Album art URL: https://static.qobuz.com/images/covers/61/76/0093624867661_600.jpg Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.797 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 110]: stream information have been fetched Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.798 [1679.1679] INFO UrlAudioSource: [0x1db0ef0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=2842396&fmt=6&profile=raw&app_id=174516466&cid=2788545&etsp=1751367465&hmac=lvbxIIbgiXEkIXqoALsvCvmvW0E Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.798 [1679.1679] INFO ContentFetcher: [0x1e066b8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=2842396&fmt=6&profile=raw&app_id=174516466&cid=2788545&etsp=1751367465&hmac=lvbxIIbgiXEkIXqoALsvCvmvW0E, offset: 0 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.798 [1679.1679] INFO AudioRenderer: [0x1a67818]: Starting audio renderer, initial playback state: Playing Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.798 [1679.1679] INFO SampleApp: [Stream 110]: New audio stream (starting from 0ms) Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: QobuzConnect: alread currrent service, send playback confirmation Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.798 [1679.1679] INFO SampleApp: Initiating playback, initial state: Playing Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 32768 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 32768 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.801 [1679.1679] INFO SampleApp: Playback state changed: Playing Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.801 [1679.1679] INFO StreamingEventReporter: [0x1a68150]: Reporting streaming started event Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.801 [1679.1679] INFO HttpDownloader: [0x1a681a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.801 [1679.1679] INFO ProtocolHandler: [0x1a67d38]: Need to send state update Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.801 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending StateUpdated message Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.801 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.801 [1679.1679] INFO QConnectMessageSender: [0x1c41318]: Sending FileAudioQualityChanged message Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.802 [1679.1679] INFO QwspMessageSender: [0x1bccde8]: Sending Payload message Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreStateMachine::pushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output update for this device Jul 01 11:57:45 valou-volumio volumio[1187]: info: MRS: Pushing multiroomSync output Jul 01 11:57:45 valou-volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] CurState: pause PrevState: play Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.820 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 111]: stream information have been fetched Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:45 valou-volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:57:45 valou-volumio volumio[1187]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jul 01 11:57:45 valou-volumio volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 110]: Metadata became available: Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Title: A Few Words From The Bride (Album Version) Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Artist: Various Interprets Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Album: Kill Bill Vol. 2 Original Soundtrack (Album Version) Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: Album art URL: https://static.qobuz.com/images/covers/61/76/0093624867661_600.jpg Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO SampleApp: [Stream 110]: Stream metadata became available: Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO SampleApp: Title: A Few Words From The Bride (Album Version) Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO SampleApp: Artist: Various Interprets Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO SampleApp: Album: Kill Bill Vol. 2 Original Soundtrack (Album Version) Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.826 [1679.1679] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/61/76/0093624867661_600.jpg Jul 01 11:57:45 valou-volumio volumio[1187]: TypeError: Cannot read property 'indexOf' of undefined Jul 01 11:57:45 valou-volumio volumio[1187]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jul 01 11:57:45 valou-volumio volumio[1187]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jul 01 11:57:45 valou-volumio volumio[1187]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jul 01 11:57:45 valou-volumio volumio[1187]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 01 11:57:45 valou-volumio volumio[1187]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jul 01 11:57:45 valou-volumio volumio[1187]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jul 01 11:57:45 valou-volumio volumio[1187]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 01 11:57:45 valou-volumio volumio[1187]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 01 11:57:45 valou-volumio volumio[1187]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jul 01 11:57:45 valou-volumio volumio[1187]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 01 11:57:45 valou-volumio volumio[1187]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jul 01 11:57:45 valou-volumio volumio[1187]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jul 01 11:57:45 valou-volumio volumio[1187]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jul 01 11:57:45 valou-volumio volumio[1187]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 01 11:57:45 valou-volumio volumio[1187]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 01 11:57:45 valou-volumio volumio[1187]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jul 01 11:57:45 valou-volumio volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.848 [1679.1679] INFO UrlAudioSource: [0x1db0ef0]: Content info extracted Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.849 [1679.1679] INFO UrlAudioSource: [0x1db0ef0]: Size: 2883862 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.853 [1679.26401] INFO AudioDecoder: [0x1bd5068]: Stream info: Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.853 [1679.26401] INFO AudioDecoder: [0x1bd5068]: Total samples: 1879253 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.853 [1679.26401] INFO AudioDecoder: [0x1bd5068]: Sample rate: 44100 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.853 [1679.26401] INFO AudioDecoder: [0x1bd5068]: Channel count: 2 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.853 [1679.26401] INFO AudioDecoder: [0x1bd5068]: Bits per sample: 16 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.853 [1679.26401] INFO AudioDecoder: [0x1bd5068]: Duration: 42613 Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.853 [1679.1679] INFO UrlAudioSource: [0x1db0ef0]: Audio properties have changed Jul 01 11:57:45 valou-volumio qobuz-connect[1679]: 20250701 11:57:45.859 [1679.1679] INFO AudioStreamManager: [0x1a676e0]: [Stream 111]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=2842397&fmt=6&profile=raw&app_id=174516466&cid=2788545&etsp=1751367465&hmac=3dN22yDUbkMM-haETn93gwH4NvI Jul 01 11:57:46 valou-volumio sudo[26410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 11:56 Jul 01 11:57:46 valou-volumio sudo[26410]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"