-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2026-02-26 09:10:31 CET. -- Feb 26 09:09:06 volumio qobuz-connect[1655]: 20260226 09:09:06.245 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Need to send state update Feb 26 09:09:06 volumio qobuz-connect[1655]: 20260226 09:09:06.245 [1655.1655] INFO QConnectMessageSender: [0x1d966c8]: Sending StateUpdated message Feb 26 09:09:06 volumio qobuz-connect[1655]: 20260226 09:09:06.245 [1655.1655] INFO QwspMessageSender: [0x1d966b8]: Sending Payload message Feb 26 09:09:06 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:06 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:09:06 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:09:06 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:09:06 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:09:06 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:09:06 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:09:06 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:11 volumio qobuz-connect[1655]: 20260226 09:09:11.635 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 4]: Audio stream has finished Feb 26 09:09:11 volumio qobuz-connect[1655]: 20260226 09:09:11.635 [1655.1655] INFO SampleApp: [Stream 4]: Audio stream finished Feb 26 09:09:11 volumio qobuz-connect[1655]: 20260226 09:09:11.635 [1655.1655] INFO SampleApp: Playback is nearly finished Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.598 [1655.1655] INFO SampleApp: Playback has finished Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.598 [1655.1655] INFO SampleApp: Nothing else to play, stopping playback Feb 26 09:09:14 volumio qobuz-connect[1655]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 4]: Disposing of audio stream Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO ContentFetcher: [0x1f96e98]: Canceling download Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO SampleApp: [Stream 4]: Audio stream has been disposed of Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO StreamingEventReporter: [0x1d5aee0]: Reporting streaming ended event Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO HttpDownloader: [0x1d5af38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Need to send state update Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO QConnectMessageSender: [0x1d966c8]: Sending StateUpdated message Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.599 [1655.1655] INFO QwspMessageSender: [0x1d966b8]: Sending Payload message Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.603 [1655.1655] INFO SampleApp: Playback state changed: Stopped Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:09:14 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:09:14 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:09:14 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Received SetState message: Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Playing state: Paused Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Playback position: Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Queue version: 2.1 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Current track: TID: 388357232, QID: 0, Context UUID: a51a825f-7913-416f-bbed-fab059248598 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Next track: TID: 388357233, QID: 1, Context UUID: a51a825f-7913-416f-bbed-fab059248598 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO MediaEngine: [0x1d5a388]: Stopping playback, clearing tracks Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO MediaEngine: [0x1d5a388]: Initiating playback Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO RendererActionAvailabilityManager: [0x1d5aa18]: Renderer action 'Next' is available Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO MediaEngine: [0x1d5a388]: Setting current track: 388357232, initial offset: 0ms Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Clearing all streams Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: New stream: 5 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO HttpDownloader: [0x1fa62e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=5267c6c203ab2d920ed0fd080862e674&request_ts=1772093354&track_id=388357232 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO HttpDownloader: [0x1fa65a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=388357232 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO HttpDownloader: [0x1f33688]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1d18947230eed0d075039dab3230cb72&request_ts=1772093354&track_id=388357232 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 5]: Running audio stream Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO MediaEngine: [0x1d5a388]: Setting next track: 388357233 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: New stream: 6 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO HttpDownloader: [0x1f0cc60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=3b76a3e10ddaab2db06acb71c04c0035&request_ts=1772093354&track_id=388357233 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO HttpDownloader: [0x20490e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=388357233 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO HttpDownloader: [0x20b3078]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a1097c335daade94e8d6f4d3ffcfa612&request_ts=1772093354&track_id=388357233 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.702 [1655.1655] INFO MediaEngine: [0x1d5a388]: Waiting for current stream to start before starting audio renderer Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.833 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357233&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772096954&hmac=H13fr_cdSD4aR9efMEYIyKdi5co Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.853 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357232&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772096954&hmac=C3C0wKF2RVEhKcTLocLe_Jfg2KI Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.886 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 5]: Metadata became available: Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.886 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Title: Nomad Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.886 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Artist: Gaerea Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.886 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album: Nomad Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.886 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album art URL: https://static.qobuz.com/images/covers/s5/m8/cov9qfx70m8s5_600.jpg Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 5]: stream information have been fetched Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO UrlAudioSource: [0x2026e00]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357232&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772096954&hmac=C3C0wKF2RVEhKcTLocLe_Jfg2KI Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO ContentFetcher: [0x2026f00]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357232&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772096954&hmac=C3C0wKF2RVEhKcTLocLe_Jfg2KI, offset: 0 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO AudioRenderer: [0x1d5a5a8]: Starting audio renderer, initial playback state: Paused Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO SampleApp: [Stream 5]: Stream metadata became available: Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO SampleApp: Title: Nomad Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO SampleApp: Artist: Gaerea Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO SampleApp: Album: Nomad Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.887 [1655.1655] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/s5/m8/cov9qfx70m8s5_600.jpg Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio volumio[1157]: info: QobuzConnect: alread currrent service, send playback confirmation Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.888 [1655.1655] INFO SampleApp: Initiating playback, initial state: Paused Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.889 [1655.1655] INFO SampleApp: Playback state changed: Paused Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.889 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Need to send state update Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.889 [1655.1655] INFO QConnectMessageSender: [0x1d966c8]: Sending StateUpdated message Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.889 [1655.1655] INFO QwspMessageSender: [0x1d966b8]: Sending Payload message Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.889 [1655.1655] INFO QConnectMessageSender: [0x1d966c8]: Sending FileAudioQualityChanged message Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.889 [1655.1655] INFO QwspMessageSender: [0x1d966b8]: Sending Payload message Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:09:14 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:09:14 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:09:14 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.892 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 6]: stream information have been fetched Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:09:14 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:09:14 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:09:14 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:09:14 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.931 [1655.1655] INFO UrlAudioSource: [0x2026e00]: Content info extracted Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.931 [1655.1655] INFO UrlAudioSource: [0x2026e00]: Size: 73944610 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.932 [1655.4639] INFO AudioDecoder: [0x20606b8]: Stream info: Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.932 [1655.4639] INFO AudioDecoder: [0x20606b8]: Total samples: 14561269 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.932 [1655.4639] INFO AudioDecoder: [0x20606b8]: Sample rate: 44100 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.932 [1655.4639] INFO AudioDecoder: [0x20606b8]: Channel count: 2 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.932 [1655.4639] INFO AudioDecoder: [0x20606b8]: Bits per sample: 24 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.932 [1655.4639] INFO AudioDecoder: [0x20606b8]: Duration: 330187 Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.932 [1655.1655] INFO UrlAudioSource: [0x2026e00]: Audio properties have changed Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.976 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 6]: Metadata became available: Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.976 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Title: Phoenix Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.976 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Artist: Gaerea Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.976 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album: Nomad Feb 26 09:09:14 volumio qobuz-connect[1655]: 20260226 09:09:14.976 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album art URL: https://static.qobuz.com/images/covers/s5/m8/cov9qfx70m8s5_600.jpg Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Received SetState message: Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Playing state: Playing Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Playback position: 0 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Queue version: 3.1 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Current track: TID: 388357232, QID: 0, Context UUID: a1eb1e56-3389-4ff5-8223-6f56960bf3c6 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Next track: TID: 388357233, QID: 1, Context UUID: a1eb1e56-3389-4ff5-8223-6f56960bf3c6 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO MediaEngine: [0x1d5a388]: Stopping playback, clearing tracks Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO MediaEngine: [0x1d5a388]: Initiating playback Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO AudioRenderer: [0x1d5a5a8]: Stopping audio renderer Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO SampleApp: Stopping playback Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Clearing all streams Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 5]: Disposing of audio stream Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.413 [1655.1655] INFO ContentFetcher: [0x2026f00]: Canceling download Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO SampleApp: Playback state changed: Stopped Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Need to send state update Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO QConnectMessageSender: [0x1d966c8]: Sending StateUpdated message Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO SampleApp: [Stream 5]: Audio stream has been disposed of Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO MediaEngine: [0x1d5a388]: Setting current track: 388357232, initial offset: 0ms Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Clearing all streams Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: New stream: 7 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO HttpDownloader: [0x2026968]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e4510683897b3c3033de9d961a0cb090&request_ts=1772093431&track_id=388357232 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO HttpDownloader: [0x1f7fe38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=388357232 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO HttpDownloader: [0x208eaa8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b16676e24b43b10432e2bcfd2d870163&request_ts=1772093431&track_id=388357232 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 7]: Running audio stream Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.416 [1655.1655] INFO QwspMessageSender: [0x1d966b8]: Sending Payload message Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.417 [1655.1655] INFO MediaEngine: [0x1d5a388]: Setting next track: 388357233 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.417 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: New stream: 8 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.417 [1655.1655] INFO HttpDownloader: [0x20b3078]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4099a16571f602566b6d1b092ca98b7c&request_ts=1772093431&track_id=388357233 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.417 [1655.1655] INFO HttpDownloader: [0x206dbb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=388357233 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.417 [1655.1655] INFO HttpDownloader: [0x1ff4988]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b02d666c36478f76891e86b5688ed02d&request_ts=1772093431&track_id=388357233 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.417 [1655.1655] INFO MediaEngine: [0x1d5a388]: Waiting for current stream to start before starting audio renderer Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.545 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357232&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772097031&hmac=YiuS9dutuQ_zzHRavUjFugt1zh0 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.586 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 8]: Metadata became available: Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.586 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Title: Phoenix Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.586 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Artist: Gaerea Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.586 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album: Nomad Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.586 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album art URL: https://static.qobuz.com/images/covers/s5/m8/cov9qfx70m8s5_600.jpg Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.608 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 7]: stream information have been fetched Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.608 [1655.1655] INFO UrlAudioSource: [0x1fb12e0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357232&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772097031&hmac=YiuS9dutuQ_zzHRavUjFugt1zh0 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.608 [1655.1655] INFO ContentFetcher: [0x1d6a5f8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357232&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772097031&hmac=YiuS9dutuQ_zzHRavUjFugt1zh0, offset: 0 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.608 [1655.1655] INFO AudioRenderer: [0x1d5a5a8]: Starting audio renderer, initial playback state: Playing Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.608 [1655.1655] INFO SampleApp: [Stream 7]: New audio stream (starting from 0ms) Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: QobuzConnect: alread currrent service, send playback confirmation Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.609 [1655.1655] INFO SampleApp: Initiating playback, initial state: Playing Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.610 [1655.1655] INFO SampleApp: Playback state changed: Playing Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.610 [1655.1655] INFO StreamingEventReporter: [0x1d5aee0]: Reporting streaming started event Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.610 [1655.1655] INFO HttpDownloader: [0x1d5af38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.610 [1655.1655] INFO ProtocolHandler: [0x1d5aac8]: Need to send state update Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.610 [1655.1655] INFO QConnectMessageSender: [0x1d966c8]: Sending StateUpdated message Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.610 [1655.1655] INFO QwspMessageSender: [0x1d966b8]: Sending Payload message Feb 26 09:10:31 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 7]: Metadata became available: Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Title: Nomad Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Artist: Gaerea Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album: Nomad Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: Album art URL: https://static.qobuz.com/images/covers/s5/m8/cov9qfx70m8s5_600.jpg Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO SampleApp: [Stream 7]: Stream metadata became available: Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO SampleApp: Title: Nomad Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO SampleApp: Artist: Gaerea Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO SampleApp: Album: Nomad Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.615 [1655.1655] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/s5/m8/cov9qfx70m8s5_600.jpg Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreStateMachine::pushState Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output update for this device Feb 26 09:10:31 volumio volumio[1157]: info: MRS: Pushing multiroomSync output Feb 26 09:10:31 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.632 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 8]: stream information have been fetched Feb 26 09:10:31 volumio volumio[1157]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Feb 26 09:10:31 volumio volumio[1157]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 09:10:31 volumio volumio[1157]: TypeError: Cannot read property 'indexOf' of undefined Feb 26 09:10:31 volumio volumio[1157]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Feb 26 09:10:31 volumio volumio[1157]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Feb 26 09:10:31 volumio volumio[1157]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Feb 26 09:10:31 volumio volumio[1157]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Feb 26 09:10:31 volumio volumio[1157]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Feb 26 09:10:31 volumio volumio[1157]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Feb 26 09:10:31 volumio volumio[1157]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Feb 26 09:10:31 volumio volumio[1157]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Feb 26 09:10:31 volumio volumio[1157]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Feb 26 09:10:31 volumio volumio[1157]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Feb 26 09:10:31 volumio volumio[1157]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Feb 26 09:10:31 volumio volumio[1157]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Feb 26 09:10:31 volumio volumio[1157]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Feb 26 09:10:31 volumio volumio[1157]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Feb 26 09:10:31 volumio volumio[1157]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Feb 26 09:10:31 volumio volumio[1157]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Feb 26 09:10:31 volumio volumio[1157]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.645 [1655.1655] INFO AudioStreamManager: [0x1d5a470]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10779474&eid=388357233&fmt=7&profile=raw&app_id=174516466&cid=3920692&etsp=1772097031&hmac=UR60xiVjW8muM30s15E5k-i2g5k Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.1655] INFO UrlAudioSource: [0x1fb12e0]: Content info extracted Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.1655] INFO UrlAudioSource: [0x1fb12e0]: Size: 73944610 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.4842] INFO AudioDecoder: [0x202f930]: Stream info: Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.4842] INFO AudioDecoder: [0x202f930]: Total samples: 14561269 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.4842] INFO AudioDecoder: [0x202f930]: Sample rate: 44100 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.4842] INFO AudioDecoder: [0x202f930]: Channel count: 2 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.4842] INFO AudioDecoder: [0x202f930]: Bits per sample: 24 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.4842] INFO AudioDecoder: [0x202f930]: Duration: 330187 Feb 26 09:10:31 volumio qobuz-connect[1655]: 20260226 09:10:31.730 [1655.1655] INFO UrlAudioSource: [0x1fb12e0]: Audio properties have changed Feb 26 09:10:31 volumio sudo[4844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 09:09 Feb 26 09:10:31 volumio sudo[4844]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"