-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Fri 2025-06-06 23:07:29 GMT. -- Jun 06 23:06:04 volumio qobuz-connect[1408]: 20250606 23:06:04.603 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:04 volumio qobuz-connect[1408]: 20250606 23:06:04.603 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:04 volumio qobuz-connect[1408]: 20250606 23:06:04.603 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:04 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:04 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:04 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:04 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:04 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:04 volumio qobuz-connect[1408]: 20250606 23:06:04.610 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:14 volumio qobuz-connect[1408]: 20250606 23:06:14.603 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:14 volumio qobuz-connect[1408]: 20250606 23:06:14.603 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:14 volumio qobuz-connect[1408]: 20250606 23:06:14.603 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:14 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:14 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:14 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:14 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:14 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:14 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:14 volumio qobuz-connect[1408]: 20250606 23:06:14.611 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:24 volumio qobuz-connect[1408]: 20250606 23:06:24.602 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:24 volumio qobuz-connect[1408]: 20250606 23:06:24.603 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:24 volumio qobuz-connect[1408]: 20250606 23:06:24.603 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:24 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:24 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:24 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:24 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:24 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:24 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:24 volumio qobuz-connect[1408]: 20250606 23:06:24.611 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:34 volumio qobuz-connect[1408]: 20250606 23:06:34.603 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:34 volumio qobuz-connect[1408]: 20250606 23:06:34.603 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:34 volumio qobuz-connect[1408]: 20250606 23:06:34.603 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:34 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:34 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:34 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:34 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:34 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:34 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:34 volumio qobuz-connect[1408]: 20250606 23:06:34.611 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Received SetState message: Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playing state: Playing Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playback position: 0 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Queue version: 2.1 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Current track: TID: 25822828, QID: 7, Context UUID: 3bf8a509-f921-4607-b8ed-bb2966492af4 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Next track: TID: 200474352, QID: 8, Context UUID: 3bf8a509-f921-4607-b8ed-bb2966492af4 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO MediaEngine: [0xa1be18]: Stopping playback, clearing tracks Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.973 [1408.1408] INFO MediaEngine: [0xa1be18]: Initiating playback Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.974 [1408.1408] INFO AudioRenderer: [0xa1c038]: Stopping audio renderer Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.974 [1408.1408] INFO SampleApp: Stopping playback Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.974 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Clearing all streams Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.974 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 1]: Disposing of audio stream Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.974 [1408.1408] INFO ContentFetcher: [0xa24580]: Canceling download Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.981 [1408.1408] INFO SampleApp: Playback state changed: Stopped Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.981 [1408.1408] INFO StreamingEventReporter: [0xa1c970]: Reporting streaming ended event Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:36 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.983 [1408.1408] INFO HttpDownloader: [0xa1c9c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO MediaEngine: [0xa1be18]: Setting current track: 25822828, initial offset: 0ms Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Clearing all streams Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: New stream: 3 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO HttpDownloader: [0xa209a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=7b656e09def2cd03e969517c64489ba2&request_ts=1749251196&track_id=25822828 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO HttpDownloader: [0xa30fb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25822828 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO HttpDownloader: [0xbbcdd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ffd1b2a674b8badfc6983fa9336590bf&request_ts=1749251196&track_id=25822828 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 3]: Running audio stream Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.984 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.987 [1408.1408] INFO MediaEngine: [0xa1be18]: Setting next track: 200474352 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.987 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: New stream: 4 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.987 [1408.1408] INFO HttpDownloader: [0xba6b70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e59db4be384e0417968cf3a5345913be&request_ts=1749251196&track_id=200474352 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.987 [1408.1408] INFO HttpDownloader: [0xb2abd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=200474352 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.987 [1408.1408] INFO HttpDownloader: [0xbc5a00]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a77c5d9746f543af329845f44bedd33f&request_ts=1749251196&track_id=200474352 Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.989 [1408.1408] INFO MediaEngine: [0xa1be18]: Waiting for current stream to start before starting audio renderer Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:36 volumio qobuz-connect[1408]: 20250606 23:06:36.993 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:36 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:36 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.004 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.059 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=25822828&fmt=27&profile=raw&app_id=174516466&cid=2814091&etsp=1749254797&hmac=_PzgZCZwRRQeDp2gCqdt6goLyJ4 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.091 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=200474352&fmt=7&profile=raw&app_id=174516466&cid=2814091&etsp=1749254797&hmac=urPm9SSjNZVEmbvfaL28xZWvfB8 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.120 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 4]: stream information have been fetched Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.123 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 3]: stream information have been fetched Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.123 [1408.1408] INFO UrlAudioSource: [0xc2c680]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=25822828&fmt=27&profile=raw&app_id=174516466&cid=2814091&etsp=1749254797&hmac=_PzgZCZwRRQeDp2gCqdt6goLyJ4 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.124 [1408.1408] INFO ContentFetcher: [0xc3b740]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=25822828&fmt=27&profile=raw&app_id=174516466&cid=2814091&etsp=1749254797&hmac=_PzgZCZwRRQeDp2gCqdt6goLyJ4, offset: 0 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.125 [1408.1408] INFO AudioRenderer: [0xa1c038]: Starting audio renderer, initial playback state: Playing Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.125 [1408.1408] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio volumio[832]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 3]: Metadata became available: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Title: Hello in There Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Artist: John Prine Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album: John Prine Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album art URL: https://static.qobuz.com/images/covers/94/57/0603497885794_600.jpg Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO SampleApp: [Stream 3]: Stream metadata became available: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO SampleApp: Title: Hello in There Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO SampleApp: Artist: John Prine Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO SampleApp: Album: John Prine Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/94/57/0603497885794_600.jpg Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.127 [1408.1408] INFO SampleApp: Initiating playback, initial state: Playing Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio volumio[832]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 06 23:06:37 volumio volumio[832]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 06 23:06:37 volumio volumio[832]: info: camilladsp stopping service pid 12827... Jun 06 23:06:37 volumio volumio[832]: info: camilladsp service terminated, instance 1 Jun 06 23:06:37 volumio volumio[832]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 06 23:06:37 volumio volumio[832]: info: camilladsp service started and running in background, instance 1 Jun 06 23:06:37 volumio volumio[832]: info: camilladsp spawned new process with pid 13138, instance 1, run: true Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.206 [1408.1408] INFO SampleApp: Playback state changed: Playing Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.206 [1408.1408] INFO StreamingEventReporter: [0xa1c970]: Reporting streaming started event Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.206 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.206 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:37 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:37 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.230 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.231 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending FileAudioQualityChanged message Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.231 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.231 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.232 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending DeviceAudioQualityChanged message Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:37 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.232 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.233 [1408.1408] INFO HttpDownloader: [0xa1c9c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.234 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 4]: Metadata became available: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.234 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Title: Circe and Penelope Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.234 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Artist: Cowboy Junkies Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.235 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album: Such Ferocious Beauty Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.235 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album art URL: https://static.qobuz.com/images/covers/na/sh/nl8kkiqucshna_600.jpg Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.236 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:37 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.243 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:37 volumio volumio[832]: info: [LastFM] Current track has sufficient metadata: title (Hello in There) and artist (John Prine) passed on explicitly Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.262 [1408.1408] INFO UrlAudioSource: [0xc2c680]: Content info extracted Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.262 [1408.1408] INFO UrlAudioSource: [0xc2c680]: Size: 187428409 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.263 [1408.13145] INFO AudioDecoder: [0xbbbf28]: Stream info: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.263 [1408.13145] INFO AudioDecoder: [0xbbbf28]: Total samples: 52165120 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.263 [1408.13145] INFO AudioDecoder: [0xbbbf28]: Sample rate: 192000 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.263 [1408.13145] INFO AudioDecoder: [0xbbbf28]: Channel count: 2 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.263 [1408.13145] INFO AudioDecoder: [0xbbbf28]: Bits per sample: 24 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.263 [1408.13145] INFO AudioDecoder: [0xbbbf28]: Duration: 271693 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.264 [1408.1408] INFO UrlAudioSource: [0xc2c680]: Audio properties have changed Jun 06 23:06:37 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 23:06:37 volumio volumio[832]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Hello In There","mbid":"c3f5d4cd-6a52-4f63-aa18-84b943db624f","url":"https://www.last.fm/music/John+Prine/_/Hello+In+There","duration":"272000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"64878","playcount":"271504","artist":{"name":"John Prine","mbid":"e86492c1-0376-4df0-8042-8ba058c83960","url":"https://www.last.fm/music/John+Prine"},"album":{"@":{"position":"3"},"artist":"John Prine","title":"John Prine","mbid":"8e04e5d7-ed79-3e2e-8558-6df68bab5f6d","url":"https://www.last.fm/music/John+Prine/John+Prine","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/f3dabb60279862c44fce1ab4f08ea985.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/f3dabb60279862c44fce1ab4f08ea985.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/f3dabb60279862c44fce1ab4f08ea985.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/f3dabb60279862c44fce1ab4f08ea985.png","@":{"size":"extralarge"}}]},"userplaycount":"29","userloved":"0","toptags":{"tag":[{"name":"folk","url":"https://www.last.fm/tag/folk"},{"name":"singer-songwriter","url":"https://www.last.fm/tag/singer-songwriter"},{"name":"country","url":"https://www.last.fm/tag/country"},{"name":"americana","url":"https://www.last.fm/tag/americana"},{"name":"john prine","url":"https://www.last.fm/tag/john+prine"}]}}} Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.833 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Received SetState message: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.833 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playing state: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.833 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playback position: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.833 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Queue version: 2.2 Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.833 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Current track: Jun 06 23:06:37 volumio qobuz-connect[1408]: 20250606 23:06:37.833 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Next track: TID: 200474352, QID: 8, Context UUID: 3bf8a509-f921-4607-b8ed-bb2966492af4 Jun 06 23:06:41 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 23:06:47 volumio qobuz-connect[1408]: 20250606 23:06:47.208 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:47 volumio qobuz-connect[1408]: 20250606 23:06:47.208 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:47 volumio qobuz-connect[1408]: 20250606 23:06:47.208 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:47 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:47 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:47 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:47 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:47 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:47 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:47 volumio qobuz-connect[1408]: 20250606 23:06:47.215 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:06:57 volumio qobuz-connect[1408]: 20250606 23:06:57.208 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:06:57 volumio qobuz-connect[1408]: 20250606 23:06:57.208 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:06:57 volumio qobuz-connect[1408]: 20250606 23:06:57.208 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:06:57 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:57 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:06:57 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:06:57 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:06:57 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:06:57 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:06:57 volumio qobuz-connect[1408]: 20250606 23:06:57.219 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:07:04 volumio volumio[832]: verbose: New Socket.io Connection to 192.168.1.194 from 192.168.1.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::volumioGetVisibleSources Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 06 23:07:04 volumio volumio[832]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 06 23:07:04 volumio volumio[832]: info: Received Get System Info Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 23:07:04 volumio volumio[832]: info: Discovery: Getting this device information Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:04 volumio volumio[832]: info: Listing playlists Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 06 23:07:04 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 06 23:07:05 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 06 23:07:07 volumio qobuz-connect[1408]: 20250606 23:07:07.208 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:07:07 volumio qobuz-connect[1408]: 20250606 23:07:07.208 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:07:07 volumio qobuz-connect[1408]: 20250606 23:07:07.209 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:07:07 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:07 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:07 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:07 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:07:07 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:07 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:07 volumio qobuz-connect[1408]: 20250606 23:07:07.220 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:07:17 volumio qobuz-connect[1408]: 20250606 23:07:17.208 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:07:17 volumio qobuz-connect[1408]: 20250606 23:07:17.209 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:07:17 volumio qobuz-connect[1408]: 20250606 23:07:17.209 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:07:17 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:17 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:17 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:17 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:07:17 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:17 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:17 volumio qobuz-connect[1408]: 20250606 23:07:17.216 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:07:27 volumio qobuz-connect[1408]: 20250606 23:07:27.209 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:07:27 volumio qobuz-connect[1408]: 20250606 23:07:27.209 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:07:27 volumio qobuz-connect[1408]: 20250606 23:07:27.209 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:07:27 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:27 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:27 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:27 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:07:27 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:27 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:27 volumio qobuz-connect[1408]: 20250606 23:07:27.221 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Received SetState message: Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playing state: Playing Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playback position: 0 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Queue version: 3.1 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Current track: TID: 4866129, QID: 39, Context UUID: 7054edb1-48a5-42d8-a5b8-c38ad3be222c Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Next track: TID: 136138991, QID: 40, Context UUID: 7054edb1-48a5-42d8-a5b8-c38ad3be222c Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO MediaEngine: [0xa1be18]: Stopping playback, clearing tracks Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO MediaEngine: [0xa1be18]: Initiating playback Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.808 [1408.1408] INFO AudioRenderer: [0xa1c038]: Stopping audio renderer Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.809 [1408.1408] INFO SampleApp: Stopping playback Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.809 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Clearing all streams Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.809 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 3]: Disposing of audio stream Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.809 [1408.1408] INFO ContentFetcher: [0xc3b740]: Canceling download Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.817 [1408.1408] INFO SampleApp: Playback state changed: Stopped Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.817 [1408.1408] INFO StreamingEventReporter: [0xa1c970]: Reporting streaming ended event Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.817 [1408.1408] INFO HttpDownloader: [0xa1c9c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.817 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.817 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.817 [1408.1408] INFO SampleApp: [Stream 3]: Audio stream has been disposed of Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO MediaEngine: [0xa1be18]: Setting current track: 4866129, initial offset: 0ms Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Clearing all streams Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: New stream: 5 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO HttpDownloader: [0xbf8f98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=5ff4f85ae51e15b019c8b6e477115d26&request_ts=1749251248&track_id=4866129 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO HttpDownloader: [0xa54f20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=4866129 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO HttpDownloader: [0xa30fb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=3f81d2d8c8416d437400176c9f70940f&request_ts=1749251248&track_id=4866129 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 5]: Running audio stream Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.818 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:28 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.821 [1408.1408] INFO MediaEngine: [0xa1be18]: Setting next track: 136138991 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.821 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: New stream: 6 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.821 [1408.1408] INFO HttpDownloader: [0xb53c30]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=3926bb989ad159e82881fbae3f7178f5&request_ts=1749251248&track_id=136138991 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.821 [1408.1408] INFO HttpDownloader: [0xb266d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=136138991 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.821 [1408.1408] INFO HttpDownloader: [0xb52508]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=98697d62aff9e60c9a9cd8b02a1f9b36&request_ts=1749251248&track_id=136138991 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.823 [1408.1408] INFO MediaEngine: [0xa1be18]: Waiting for current stream to start before starting audio renderer Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.827 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:28 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.838 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.892 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=4866129&fmt=27&profile=raw&app_id=174516466&cid=2814091&etsp=1749254848&hmac=etU1ToQNtwCHBephOeOwfqLndJU Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.908 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=136138991&fmt=7&profile=raw&app_id=174516466&cid=2814091&etsp=1749254848&hmac=WM17_wvVW69U45ROPwR8tAilIo8 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.966 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 5]: stream information have been fetched Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.966 [1408.1408] INFO UrlAudioSource: [0xbb9880]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=4866129&fmt=27&profile=raw&app_id=174516466&cid=2814091&etsp=1749254848&hmac=etU1ToQNtwCHBephOeOwfqLndJU Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.967 [1408.1408] INFO ContentFetcher: [0xc15ba0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=5005241&eid=4866129&fmt=27&profile=raw&app_id=174516466&cid=2814091&etsp=1749254848&hmac=etU1ToQNtwCHBephOeOwfqLndJU, offset: 0 Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.967 [1408.1408] INFO AudioRenderer: [0xa1c038]: Starting audio renderer, initial playback state: Playing Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.967 [1408.1408] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:28 volumio volumio[832]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.970 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 6]: stream information have been fetched Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.971 [1408.1408] INFO SampleApp: Initiating playback, initial state: Playing Jun 06 23:07:28 volumio volumio[832]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 06 23:07:28 volumio volumio[832]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 06 23:07:28 volumio volumio[832]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.983 [1408.1408] INFO SampleApp: Playback state changed: Playing Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.983 [1408.1408] INFO StreamingEventReporter: [0xa1c970]: Reporting streaming started event Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.983 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Need to send state update Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.983 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending StateUpdated message Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.984 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.985 [1408.1408] INFO QConnectMessageSender: [0xa55ef0]: Sending FileAudioQualityChanged message Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.985 [1408.1408] INFO HttpDownloader: [0xa1c9c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.986 [1408.1408] INFO QwspMessageSender: [0xaf7600]: Sending Payload message Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.987 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 5]: Metadata became available: Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.987 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Title: Hurricane (Album Version) Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.987 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Artist: Bob Dylan Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.987 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album: Desire (Album Version) Jun 06 23:07:28 volumio qobuz-connect[1408]: 20250606 23:07:28.987 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album art URL: https://static.qobuz.com/images/covers/22/93/0827969239322_600.jpg Jun 06 23:07:28 volumio volumio[832]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 06 23:07:28 volumio volumio[832]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 06 23:07:28 volumio volumio[832]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:28 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:07:28 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:29 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:29 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::servicePushState Jun 06 23:07:29 volumio volumio[832]: info: CoreStateMachine::pushState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Jun 06 23:07:29 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Jun 06 23:07:29 volumio volumio[832]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 06 23:07:29 volumio volumio[832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 23:07:29 volumio volumio[832]: TypeError: Cannot read property 'indexOf' of undefined Jun 06 23:07:29 volumio volumio[832]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 06 23:07:29 volumio volumio[832]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 06 23:07:29 volumio volumio[832]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 06 23:07:29 volumio volumio[832]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 06 23:07:29 volumio volumio[832]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 06 23:07:29 volumio volumio[832]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 06 23:07:29 volumio volumio[832]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 06 23:07:29 volumio volumio[832]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 06 23:07:29 volumio volumio[832]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 06 23:07:29 volumio volumio[832]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 06 23:07:29 volumio volumio[832]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 06 23:07:29 volumio volumio[832]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 06 23:07:29 volumio volumio[832]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 06 23:07:29 volumio volumio[832]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 06 23:07:29 volumio volumio[832]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 06 23:07:29 volumio volumio[832]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 06 23:07:29 volumio volumio[832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.060 [1408.1408] INFO SampleApp: [Stream 5]: Stream metadata became available: Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.060 [1408.1408] INFO SampleApp: Title: Hurricane (Album Version) Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.060 [1408.1408] INFO SampleApp: Artist: Bob Dylan Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.060 [1408.1408] INFO SampleApp: Album: Desire (Album Version) Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.060 [1408.1408] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/22/93/0827969239322_600.jpg Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.061 [1408.1408] WARNING SampleApp: Command playback_set_volume misses volume argument Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.084 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: [Stream 6]: Metadata became available: Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.084 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Title: Change Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.084 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Artist: Big Thief Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.084 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album: Dragon New Warm Mountain I Believe In You Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.084 [1408.1408] INFO AudioStreamManager: [0xa1bf00]: Album art URL: https://static.qobuz.com/images/covers/ba/d0/ej2nc8ycad0ba_600.jpg Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.123 [1408.1408] INFO UrlAudioSource: [0xbb9880]: Content info extracted Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.123 [1408.1408] INFO UrlAudioSource: [0xbb9880]: Size: 450002897 Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.124 [1408.13277] INFO AudioDecoder: [0xc87b60]: Stream info: Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.124 [1408.13277] INFO AudioDecoder: [0xc87b60]: Total samples: 98421023 Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.124 [1408.13277] INFO AudioDecoder: [0xc87b60]: Sample rate: 192000 Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.124 [1408.13277] INFO AudioDecoder: [0xc87b60]: Channel count: 2 Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.124 [1408.13277] INFO AudioDecoder: [0xc87b60]: Bits per sample: 24 Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.124 [1408.13277] INFO AudioDecoder: [0xc87b60]: Duration: 512609 Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.125 [1408.1408] INFO UrlAudioSource: [0xbb9880]: Audio properties have changed Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.687 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Received SetState message: Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.687 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playing state: Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.687 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Playback position: Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.687 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Queue version: 3.2 Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.687 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Current track: Jun 06 23:07:29 volumio qobuz-connect[1408]: 20250606 23:07:29.687 [1408.1408] INFO ProtocolHandler: [0xa1c558]: Next track: TID: 136138991, QID: 40, Context UUID: 7054edb1-48a5-42d8-a5b8-c38ad3be222c Jun 06 23:07:29 volumio sudo[13283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-06 23:06 Jun 06 23:07:29 volumio sudo[13283]: 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"