-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-05-25 09:27:26 CEST. -- May 25 09:26:00 valou-volumio qobuz-connect[1570]: 20250525 09:26:00.956 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:00 valou-volumio qobuz-connect[1570]: 20250525 09:26:00.956 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:00 valou-volumio qobuz-connect[1570]: 20250525 09:26:00.956 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:00 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:00 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:00 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:00 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:00 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:00 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:00 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:00 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:00 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:00 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:10 valou-volumio qobuz-connect[1570]: 20250525 09:26:10.956 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:10 valou-volumio qobuz-connect[1570]: 20250525 09:26:10.956 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:10 valou-volumio qobuz-connect[1570]: 20250525 09:26:10.956 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:10 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:10 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:10 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:10 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:10 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:10 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:10 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:10 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:10 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:10 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:20 valou-volumio qobuz-connect[1570]: 20250525 09:26:20.957 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:20 valou-volumio qobuz-connect[1570]: 20250525 09:26:20.957 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:20 valou-volumio qobuz-connect[1570]: 20250525 09:26:20.957 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:20 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:20 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:20 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:20 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:20 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:20 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:20 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:20 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:20 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:20 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:30 valou-volumio qobuz-connect[1570]: 20250525 09:26:30.957 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:30 valou-volumio qobuz-connect[1570]: 20250525 09:26:30.957 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:30 valou-volumio qobuz-connect[1570]: 20250525 09:26:30.958 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:30 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:30 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:30 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:30 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:30 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:30 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:30 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:30 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:30 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:30 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 62]: Running audio stream May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO UrlAudioSource: [0x9d4760]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=50324249&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1748161291&hmac=94Fei2yqZ5AituO8noU8ZMiovHc May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO ContentFetcher: [0xaf02b8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=50324249&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1748161291&hmac=94Fei2yqZ5AituO8noU8ZMiovHc, offset: 0 May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO SampleApp: [Stream 62]: New audio stream (starting from 0ms) May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO SampleApp: [Stream 62]: Stream metadata became available: May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO SampleApp: Title: Évasion de Julien (Take 2 - Bande originale du film "Ascenseur pour l'échafaud") May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO SampleApp: Artist: Miles Davis May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO SampleApp: Album: Ascenseur pour l'échafaud (Take 2 - Bande originale du film "Ascenseur pour l'échafaud") May 25 09:26:36 valou-volumio qobuz-connect[1570]: 20250525 09:26:36.859 [1570.1570] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/oc/yv/po8isu85cyvoc_600.jpg May 25 09:26:36 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.365 [1570.1570] INFO UrlAudioSource: [0x9d4760]: Content info extracted May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.365 [1570.1570] INFO UrlAudioSource: [0x9d4760]: Size: 20833127 May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.368 [1570.30035] INFO AudioDecoder: [0xb19f98]: Stream info: May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.368 [1570.30035] INFO AudioDecoder: [0xb19f98]: Total samples: 11292672 May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.368 [1570.30035] INFO AudioDecoder: [0xb19f98]: Sample rate: 96000 May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.368 [1570.30035] INFO AudioDecoder: [0xb19f98]: Channel count: 2 May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.368 [1570.30035] INFO AudioDecoder: [0xb19f98]: Bits per sample: 24 May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.368 [1570.30035] INFO AudioDecoder: [0xb19f98]: Duration: 117632 May 25 09:26:37 valou-volumio qobuz-connect[1570]: 20250525 09:26:37.368 [1570.1570] INFO UrlAudioSource: [0x9d4760]: Audio properties have changed May 25 09:26:40 valou-volumio qobuz-connect[1570]: 20250525 09:26:40.957 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:40 valou-volumio qobuz-connect[1570]: 20250525 09:26:40.957 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:40 valou-volumio qobuz-connect[1570]: 20250525 09:26:40.957 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:40 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:40 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:40 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:40 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:40 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:40 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:40 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:40 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:40 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:40 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.517 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 61]: Audio stream has finished May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.517 [1570.1570] INFO SampleApp: [Stream 61]: Audio stream finished May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.517 [1570.1570] INFO SampleApp: Playback is nearly finished May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.853 [1570.1570] INFO SampleApp: Playback has finished May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.853 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 61]: Disposing of audio stream May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.853 [1570.1570] INFO ContentFetcher: [0xbaa530]: Canceling download May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.854 [1570.1570] INFO SampleApp: [Stream 61]: Audio stream has been disposed of May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.854 [1570.1570] INFO StreamingEventReporter: [0x78c150]: Reporting streaming ended event May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.854 [1570.1570] INFO HttpDownloader: [0x78c1a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.854 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.854 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.854 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.855 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.855 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.855 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.855 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending FileAudioQualityChanged message May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.855 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.855 [1570.1570] INFO StreamingEventReporter: [0x78c150]: Reporting streaming started event May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:46 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:46 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:46 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:46 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:46 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:46 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:46 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:46 valou-volumio volumio[1088]: info: [LastFM] Current track has sufficient metadata: title (Évasion de Julien (Take 2 - Bande originale du film "Ascenseur pour l'échafaud")) and artist (Miles Davis) passed on explicitly May 25 09:26:46 valou-volumio volumio[1088]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) May 25 09:26:46 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:46 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:46 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:46 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:46 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Received SetState message: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Playing state: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Playback position: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Queue version: 9.2 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Current track: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Next track: TID: 50324250, QID: 23, Context UUID: e265bd0b-90cf-456d-a898-ae3ef67983cb May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO MediaEngine: [0x78b5f8]: Setting next track: 50324250 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: New stream: 63 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO HttpDownloader: [0xa87c58]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=1cde7c13d3775c51597373faf7836e40&request_ts=1748158006&track_id=50324250 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO HttpDownloader: [0x7fd8c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=50324250 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.922 [1570.1570] INFO HttpDownloader: [0xa92658]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=696cbdf2d670f8c518c34db3f525c4ca&request_ts=1748158006&track_id=50324250 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.937 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Received SetState message: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.937 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Playing state: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.937 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Playback position: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.937 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Queue version: 9.2 May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.937 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Current track: May 25 09:26:46 valou-volumio qobuz-connect[1570]: 20250525 09:26:46.937 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Next track: TID: 50324250, QID: 23, Context UUID: e265bd0b-90cf-456d-a898-ae3ef67983cb May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.073 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 63]: stream information have been fetched May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.079 [1570.1570] INFO HttpDownloader: [0x78c1a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.101 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 63]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=50324250&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1748161607&hmac=GopPMfWqZne83be83EUBArR1SZ4 May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.141 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 63]: Metadata became available: May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.141 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Title: Le petit bal (Take 1 - Bande originale du film "Ascenseur pour l'échafaud") May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.141 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Artist: Miles Davis May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.141 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Album: Ascenseur pour l'échafaud (Take 1 - Bande originale du film "Ascenseur pour l'échafaud") May 25 09:26:47 valou-volumio qobuz-connect[1570]: 20250525 09:26:47.141 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Album art URL: https://static.qobuz.com/images/covers/oc/yv/po8isu85cyvoc_600.jpg May 25 09:26:56 valou-volumio qobuz-connect[1570]: 20250525 09:26:56.854 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:26:56 valou-volumio qobuz-connect[1570]: 20250525 09:26:56.854 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:26:56 valou-volumio qobuz-connect[1570]: 20250525 09:26:56.854 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:26:56 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:56 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:26:56 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:26:56 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:26:56 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:26:56 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:26:56 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:26:56 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:26:56 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:26:56 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:27:06 valou-volumio qobuz-connect[1570]: 20250525 09:27:06.855 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:27:06 valou-volumio qobuz-connect[1570]: 20250525 09:27:06.855 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:27:06 valou-volumio qobuz-connect[1570]: 20250525 09:27:06.855 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:27:06 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:06 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:06 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:06 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:27:06 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:06 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:06 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:06 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:06 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:27:06 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:27:16 valou-volumio qobuz-connect[1570]: 20250525 09:27:16.855 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:27:16 valou-volumio qobuz-connect[1570]: 20250525 09:27:16.855 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:27:16 valou-volumio qobuz-connect[1570]: 20250525 09:27:16.855 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:27:16 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:16 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:16 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:16 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:27:16 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:16 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:16 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:16 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:16 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:27:16 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Received SetState message: May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Playing state: Playing May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Playback position: 0 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Queue version: 10.1 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Current track: TID: 161505237, QID: 0, Context UUID: 9ebe6e33-d0c9-4cb6-bbe8-d2d8d30e0486 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Next track: TID: 161505238, QID: 1, Context UUID: 9ebe6e33-d0c9-4cb6-bbe8-d2d8d30e0486 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO MediaEngine: [0x78b5f8]: Stopping playback, clearing tracks May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO MediaEngine: [0x78b5f8]: Initiating playback May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO AudioRenderer: [0x78b818]: Stopping audio renderer May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.902 [1570.1570] INFO SampleApp: Stopping playback May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.903 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Clearing all streams May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.903 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 62]: Disposing of audio stream May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.903 [1570.1570] INFO ContentFetcher: [0xaf02b8]: Canceling download May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.906 [1570.1570] INFO SampleApp: Playback state changed: Stopped May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.906 [1570.1570] INFO StreamingEventReporter: [0x78c150]: Reporting streaming ended event May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO HttpDownloader: [0x78c1a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:25 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO SampleApp: [Stream 62]: Audio stream has been disposed of May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO MediaEngine: [0x78b5f8]: Setting current track: 161505237, initial offset: 0ms May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Clearing all streams May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: New stream: 64 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO HttpDownloader: [0xb99e40]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d89304cb641948a992c52a57a7c4bd36&request_ts=1748158045&track_id=161505237 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO HttpDownloader: [0x7fe228]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=161505237 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO HttpDownloader: [0xaeaf38]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6b3b30161a30e030f71a6aed2ce54752&request_ts=1748158045&track_id=161505237 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 64]: Running audio stream May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.907 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.909 [1570.1570] INFO MediaEngine: [0x78b5f8]: Setting next track: 161505238 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.909 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: New stream: 65 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.909 [1570.1570] INFO HttpDownloader: [0xcfd858]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=86bd15ca22efc49fbf436b9c768638b2&request_ts=1748158045&track_id=161505238 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.909 [1570.1570] INFO HttpDownloader: [0x9fe5a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=161505238 May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.909 [1570.1570] INFO HttpDownloader: [0x999cc8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=55b0458e3148da0f046b8c5ee6fa1c23&request_ts=1748158045&track_id=161505238 May 25 09:27:25 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:25 valou-volumio qobuz-connect[1570]: 20250525 09:27:25.910 [1570.1570] INFO MediaEngine: [0x78b5f8]: Waiting for current stream to start before starting audio renderer May 25 09:27:25 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:25 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:25 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:25 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:25 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:25 valou-volumio volumio[1088]: info: [ASDebug] CurState: pause PrevState: play May 25 09:27:25 valou-volumio volumio[1088]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 25 09:27:25 valou-volumio volumio[1088]: info: [ASDebug] CurState: pause PrevState: play May 25 09:27:25 valou-volumio volumio[1088]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 25 09:27:25 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:27:25 valou-volumio volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.007 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 65]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=161505238&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1748161645&hmac=aI6z87Baw-cFXS7hLHsFKmm0ALA May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.036 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 64]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=161505237&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1748161645&hmac=-AFljAqfbEQvSAtbm6QHi8Y2rTc May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.042 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 64]: stream information have been fetched May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.042 [1570.1570] INFO UrlAudioSource: [0xbce238]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=161505237&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1748161645&hmac=-AFljAqfbEQvSAtbm6QHi8Y2rTc May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.042 [1570.1570] INFO ContentFetcher: [0xba5138]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1671453&eid=161505237&fmt=7&profile=raw&app_id=174516466&cid=2788545&etsp=1748161645&hmac=-AFljAqfbEQvSAtbm6QHi8Y2rTc, offset: 0 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.043 [1570.1570] INFO AudioRenderer: [0x78b818]: Starting audio renderer, initial playback state: Playing May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.043 [1570.1570] INFO SampleApp: [Stream 64]: New audio stream (starting from 0ms) May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: QobuzConnect: alread currrent service, send playback confirmation May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.044 [1570.1570] INFO SampleApp: Initiating playback, initial state: Playing May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO SampleApp: Playback state changed: Playing May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO StreamingEventReporter: [0x78c150]: Reporting streaming started event May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO ProtocolHandler: [0x78bd38]: Need to send state update May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending StateUpdated message May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending FileAudioQualityChanged message May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.046 [1570.1570] INFO QConnectMessageSender: [0x7cb7e8]: Sending DeviceAudioQualityChanged message May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.047 [1570.1570] INFO QwspMessageSender: [0x895d58]: Sending Payload message May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreStateMachine::pushState May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.056 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 65]: stream information have been fetched May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output update for this device May 25 09:27:26 valou-volumio volumio[1088]: info: MRS: Pushing multiroomSync output May 25 09:27:26 valou-volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 25 09:27:26 valou-volumio volumio[1088]: info: [ASDebug] CurState: pause PrevState: play May 25 09:27:26 valou-volumio volumio[1088]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 25 09:27:26 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:27:26 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:27:26 valou-volumio volumio[1088]: info: [ASDebug] CurState: play PrevState: play May 25 09:27:26 valou-volumio volumio[1088]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 25 09:27:26 valou-volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 64]: Metadata became available: May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Title: Badeep May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Artist: GoGo Penguin May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Album: Between Two Waves (EP) May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Album art URL: https://static.qobuz.com/images/covers/1b/d0/ve44bi69id01b_600.jpg May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO SampleApp: [Stream 64]: Stream metadata became available: May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO SampleApp: Title: Badeep May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO SampleApp: Artist: GoGo Penguin May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO SampleApp: Album: Between Two Waves (EP) May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.072 [1570.1570] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/1b/d0/ve44bi69id01b_600.jpg May 25 09:27:26 valou-volumio volumio[1088]: TypeError: Cannot read property 'indexOf' of undefined May 25 09:27:26 valou-volumio volumio[1088]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 25 09:27:26 valou-volumio volumio[1088]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 25 09:27:26 valou-volumio volumio[1088]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 25 09:27:26 valou-volumio volumio[1088]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 25 09:27:26 valou-volumio volumio[1088]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 25 09:27:26 valou-volumio volumio[1088]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 25 09:27:26 valou-volumio volumio[1088]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 25 09:27:26 valou-volumio volumio[1088]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 25 09:27:26 valou-volumio volumio[1088]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 25 09:27:26 valou-volumio volumio[1088]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 25 09:27:26 valou-volumio volumio[1088]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 25 09:27:26 valou-volumio volumio[1088]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 25 09:27:26 valou-volumio volumio[1088]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 25 09:27:26 valou-volumio volumio[1088]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 25 09:27:26 valou-volumio volumio[1088]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 25 09:27:26 valou-volumio volumio[1088]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 25 09:27:26 valou-volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.078 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: [Stream 65]: Metadata became available: May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.079 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Title: Ascent May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.079 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Artist: GoGo Penguin May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.079 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Album: Between Two Waves (EP) May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.079 [1570.1570] INFO AudioStreamManager: [0x78b6e0]: Album art URL: https://static.qobuz.com/images/covers/1b/d0/ve44bi69id01b_600.jpg May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.083 [1570.1570] INFO UrlAudioSource: [0xbce238]: Content info extracted May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.083 [1570.1570] INFO UrlAudioSource: [0xbce238]: Size: 73208181 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.083 [1570.30159] INFO AudioDecoder: [0xd46808]: Stream info: May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.083 [1570.30159] INFO AudioDecoder: [0xd46808]: Total samples: 17450606 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.083 [1570.30159] INFO AudioDecoder: [0xd46808]: Sample rate: 48000 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.083 [1570.30159] INFO AudioDecoder: [0xd46808]: Channel count: 2 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.084 [1570.30159] INFO AudioDecoder: [0xd46808]: Bits per sample: 24 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.084 [1570.30159] INFO AudioDecoder: [0xd46808]: Duration: 363554 May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.084 [1570.1570] INFO UrlAudioSource: [0xbce238]: Audio properties have changed May 25 09:27:26 valou-volumio qobuz-connect[1570]: 20250525 09:27:26.099 [1570.1570] INFO HttpDownloader: [0x78c1a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 25 09:27:26 valou-volumio sudo[30169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 09:26 May 25 09:27:26 valou-volumio sudo[30169]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"