-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Sun 2026-03-29 11:43:31 EDT. -- Mar 29 11:42:04 volumio qobuz-connect[1523]: 20260329 11:42:04.933 [1523.1523] ERROR HttpClient: [0xe0e318]: Client finished: Timeout was reached (28) Mar 29 11:42:04 volumio qobuz-connect[1523]: 20260329 11:42:04.933 [1523.1523] INFO HttpClient: [0xe0e318]: Retrying connection (1 out of 1) Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.918 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.918 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.918 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.926-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.926-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Land Ho (Single Version/Remix)" Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] ERROR HttpClient: [0xe0e318]: Client finished: Timeout was reached (28) Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] ERROR HttpClient: [0xe0e318]: Failed to connect after 1 retries Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] ERROR UrlAudioSource: [0xddf2b8]: Content fetcher has encountered an error Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] INFO ContentFetcher: [0xea67b8]: Canceling download Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 27]: Audio stream has encountered an error Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.948 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.949 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.949 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.949 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.949 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.949 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.949 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.957-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.958-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Land Ho (Single Version/Remix)" Mar 29 11:42:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.971-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.971-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Land Ho (Single Version/Remix)" Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 112884231, QID: 15, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 401068888, QID: 16, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 112884231, initial offset: 0ms Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 27]: Disposing of audio stream Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 29 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO HttpDownloader: [0xf73348]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ae347ccbce6b2003d1cf3759ca6407ca&request_ts=1774798929&track_id=112884231 Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO HttpDownloader: [0xdcd2b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=112884231 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.980 [1523.1523] INFO HttpDownloader: [0xddae38]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=41ac6c37c840de9a55b1685ec53cd635&request_ts=1774798929&track_id=112884231 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 29]: Running audio stream Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO SampleApp: [Stream 27]: Audio stream has been disposed of Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 401068888 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 30 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO HttpDownloader: [0xf67228]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=2fe9d88c63f1f995360f5ca58f2727ef&request_ts=1774798929&track_id=401068888 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO HttpDownloader: [0xf63b58]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401068888 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO HttpDownloader: [0xf0b178]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=aa06ccfb1d165051a7b37c2a0a020f6f&request_ts=1774798929&track_id=401068888 Mar 29 11:42:09 volumio qobuz-connect[1523]: 20260329 11:42:09.981 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:09 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:09 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.989-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:09 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:09.989-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Land Ho (Single Version/Remix)" Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.093 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 29]: Metadata became available: Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.093 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Babaji Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.093 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.093 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Even In The Quietest Moments... Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.093 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/db/hm/pt87el6f1hmdb_600.jpg Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.115 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 30]: Metadata became available: Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.115 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Crazy Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.115 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.115 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: "...Famous Last Words..." Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.115 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/ck/dz/cdbeh9a9sdzck_600.jpg Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.149 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 29]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=112884231&fmt=7&profile=raw&app_id=174516466&cid=2332902&etsp=1774802530&hmac=jKVqvtuv3MV2KtbmczhFkrTE7Qg Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.149 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 30]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068888&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802530&hmac=HENybjbQorwKuCnc6CPWU-Q43R8 Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.199 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 30]: stream information have been fetched Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 29]: stream information have been fetched Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO UrlAudioSource: [0xed8708]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=112884231&fmt=7&profile=raw&app_id=174516466&cid=2332902&etsp=1774802530&hmac=jKVqvtuv3MV2KtbmczhFkrTE7Qg Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO ContentFetcher: [0xed5aa8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=112884231&fmt=7&profile=raw&app_id=174516466&cid=2332902&etsp=1774802530&hmac=jKVqvtuv3MV2KtbmczhFkrTE7Qg, offset: 0 Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO SampleApp: [Stream 29]: New audio stream (starting from 0ms) Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO SampleApp: [Stream 29]: Stream metadata became available: Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO SampleApp: Title: Babaji Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO SampleApp: Album: Even In The Quietest Moments... Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.314 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/db/hm/pt87el6f1hmdb_600.jpg Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.315 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.318 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.318 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.318 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.318 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.318 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.318 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.318 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending FileAudioQualityChanged message Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:10 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.319 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.319 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending DeviceAudioQualityChanged message Mar 29 11:42:10 volumio qobuz-connect[1523]: 20260329 11:42:10.319 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:10 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:42:10 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:10 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:10 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.331-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.331-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.333-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.333-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:10 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:10 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:10 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:10 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.363-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.364-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.366-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:10 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:10.366-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:15 volumio qobuz-connect[1523]: 20260329 11:42:15.360 [1523.1523] ERROR HttpClient: [0xf77f80]: Client finished: Timeout was reached (28) Mar 29 11:42:15 volumio qobuz-connect[1523]: 20260329 11:42:15.360 [1523.1523] INFO HttpClient: [0xf77f80]: Retrying connection (1 out of 1) Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.318 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.318 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.318 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.326-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.327-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] ERROR HttpClient: [0xf77f80]: Client finished: Timeout was reached (28) Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] ERROR HttpClient: [0xf77f80]: Failed to connect after 1 retries Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] ERROR UrlAudioSource: [0xed8708]: Content fetcher has encountered an error Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] INFO ContentFetcher: [0xed5aa8]: Canceling download Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 29]: Audio stream has encountered an error Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.375 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.376 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.376 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.376 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.376 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.376 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.376 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.376 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.389-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.390-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.391-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.392-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 401068888, QID: 16, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 754749, QID: 17, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 401068888, initial offset: 0ms Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 29]: Disposing of audio stream Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 31 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO HttpDownloader: [0xf73348]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=7efe12d9baf69e6cd26cf0d263b09ff1&request_ts=1774798940&track_id=401068888 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO HttpDownloader: [0xecc360]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401068888 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO HttpDownloader: [0xed13d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6d564da30910139edc5923fbc5f88cae&request_ts=1774798940&track_id=401068888 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 31]: Running audio stream Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO SampleApp: [Stream 29]: Audio stream has been disposed of Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 754749 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 32 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.406 [1523.1523] INFO HttpDownloader: [0xf21fa8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=19ff23299d96609de59a52420080336c&request_ts=1774798940&track_id=754749 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.407 [1523.1523] INFO HttpDownloader: [0xe2d1d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=754749 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.407 [1523.1523] INFO HttpDownloader: [0xf294b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=0e06807e331b65daf1f922935470d6fb&request_ts=1774798940&track_id=754749 Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.408 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.414-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.414-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Babaji Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.522 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 32]: Metadata became available: Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.522 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: I'm Beggin' You (Album Version) Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.522 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.522 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Free As A Bird (Album Version) Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.522 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/52/35/0060694933552_600.jpg Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.527 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 31]: Metadata became available: Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.527 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Crazy Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.527 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.527 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: "...Famous Last Words..." Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.527 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/ck/dz/cdbeh9a9sdzck_600.jpg Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.561 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 32]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754749&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802540&hmac=Yi-AOZx1JrdH1A28JdiLAc3CMwY Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.568 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 31]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068888&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802540&hmac=ND3mfCJWzt5OJbDbNlIZSCwhUCY Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.642 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 32]: stream information have been fetched Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 31]: stream information have been fetched Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO UrlAudioSource: [0xec9a70]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068888&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802540&hmac=ND3mfCJWzt5OJbDbNlIZSCwhUCY Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO ContentFetcher: [0xf87000]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068888&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802540&hmac=ND3mfCJWzt5OJbDbNlIZSCwhUCY, offset: 0 Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO SampleApp: [Stream 31]: New audio stream (starting from 0ms) Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO SampleApp: [Stream 31]: Stream metadata became available: Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO SampleApp: Title: Crazy Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO SampleApp: Album: "...Famous Last Words..." Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.651 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ck/dz/cdbeh9a9sdzck_600.jpg Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.653 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.655 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.655 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending FileAudioQualityChanged message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending DeviceAudioQualityChanged message Mar 29 11:42:20 volumio qobuz-connect[1523]: 20260329 11:42:20.656 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:42:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.664-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.664-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:20 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:20 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.688-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.688-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.691-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.691-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.692-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:20 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:20.693-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:25 volumio qobuz-connect[1523]: 20260329 11:42:25.667 [1523.1523] ERROR HttpClient: [0xec9998]: Client finished: Timeout was reached (28) Mar 29 11:42:25 volumio qobuz-connect[1523]: 20260329 11:42:25.667 [1523.1523] INFO HttpClient: [0xec9998]: Retrying connection (1 out of 1) Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.655 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.655 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.655 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.662-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.663-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.683 [1523.1523] ERROR HttpClient: [0xec9998]: Client finished: Timeout was reached (28) Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.683 [1523.1523] ERROR HttpClient: [0xec9998]: Failed to connect after 1 retries Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.683 [1523.1523] ERROR UrlAudioSource: [0xec9a70]: Content fetcher has encountered an error Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.683 [1523.1523] INFO ContentFetcher: [0xf87000]: Canceling download Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.683 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 31]: Audio stream has encountered an error Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.683 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.683 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.684 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.694-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.694-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.705-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.705-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 754749, QID: 17, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 401068893, QID: 18, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 754749, initial offset: 0ms Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 31]: Disposing of audio stream Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.713 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 33 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.714 [1523.1523] INFO HttpDownloader: [0xec9b20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=3add42192ef73fb7154a29e66b83b9dd&request_ts=1774798950&track_id=754749 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.714 [1523.1523] INFO HttpDownloader: [0xe0b548]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=754749 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.714 [1523.1523] INFO HttpDownloader: [0x1164fb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d6d41a9865b0f34606b3d22002028652&request_ts=1774798950&track_id=754749 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.714 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 33]: Running audio stream Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.714 [1523.1523] INFO SampleApp: [Stream 31]: Audio stream has been disposed of Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.715 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 401068893 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.715 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 34 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.715 [1523.1523] INFO HttpDownloader: [0xe0abe8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e9daa16a2af1a2dfda54059b5bd30831&request_ts=1774798950&track_id=401068893 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.715 [1523.1523] INFO HttpDownloader: [0xf0dee8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401068893 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.715 [1523.1523] INFO HttpDownloader: [0x11f8fa0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a79115913d8e1c92632d7bee6976505b&request_ts=1774798950&track_id=401068893 Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.715 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.722-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.722-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title=Crazy Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.841 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 33]: Metadata became available: Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.841 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: I'm Beggin' You (Album Version) Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.841 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.841 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Free As A Bird (Album Version) Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.841 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/52/35/0060694933552_600.jpg Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.847 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 34]: Metadata became available: Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.847 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: My Kind Of Lady Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.847 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.847 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: "...Famous Last Words..." Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.847 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/ck/dz/cdbeh9a9sdzck_600.jpg Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.885 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 34]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068893&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802550&hmac=o_wTf6WCGz_dlo8SNnoquULYPEI Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.894 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 33]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754749&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802550&hmac=z27TYvyO2X0Hr0XqIk5tl5RVI-k Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 33]: stream information have been fetched Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO UrlAudioSource: [0xe11388]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754749&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802550&hmac=z27TYvyO2X0Hr0XqIk5tl5RVI-k Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO ContentFetcher: [0xf87000]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754749&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802550&hmac=z27TYvyO2X0Hr0XqIk5tl5RVI-k, offset: 0 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO SampleApp: [Stream 33]: New audio stream (starting from 0ms) Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO SampleApp: [Stream 33]: Stream metadata became available: Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO SampleApp: Title: I'm Beggin' You (Album Version) Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO SampleApp: Album: Free As A Bird (Album Version) Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.915 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/52/35/0060694933552_600.jpg Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.916 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.919 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.919 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:42:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.920 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.920 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.920 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.920 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending FileAudioQualityChanged message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.920 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.920 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending DeviceAudioQualityChanged message Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.920 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.928-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.928-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio qobuz-connect[1523]: 20260329 11:42:30.942 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:30 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.951-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.952-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.953-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.954-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.955-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:30 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:30.955-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:31 volumio qobuz-connect[1523]: 20260329 11:42:31.195 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 34]: stream information have been fetched Mar 29 11:42:35 volumio qobuz-connect[1523]: 20260329 11:42:35.926 [1523.1523] ERROR HttpClient: [0xec9998]: Client finished: Timeout was reached (28) Mar 29 11:42:35 volumio qobuz-connect[1523]: 20260329 11:42:35.926 [1523.1523] INFO HttpClient: [0xec9998]: Retrying connection (1 out of 1) Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.920 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.920 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.920 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.929-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.929-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] ERROR HttpClient: [0xec9998]: Client finished: Timeout was reached (28) Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] ERROR HttpClient: [0xec9998]: Failed to connect after 1 retries Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] ERROR UrlAudioSource: [0xe11388]: Content fetcher has encountered an error Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO ContentFetcher: [0xf87000]: Canceling download Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 33]: Audio stream has encountered an error Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.943 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.944 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.944 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.944 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.952-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.952-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.963-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.963-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 401068893, QID: 18, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 52435559, QID: 19, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 401068893, initial offset: 0ms Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 33]: Disposing of audio stream Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 35 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.973 [1523.1523] INFO HttpDownloader: [0xec9b20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d3535f241cc4ff0d1b74a3c053416243&request_ts=1774798960&track_id=401068893 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.974 [1523.1523] INFO HttpDownloader: [0xe0b548]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401068893 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.974 [1523.1523] INFO HttpDownloader: [0x1164fb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4e41b557d18ce11901a1578314ce4240&request_ts=1774798960&track_id=401068893 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.974 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 35]: Running audio stream Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.974 [1523.1523] INFO SampleApp: [Stream 33]: Audio stream has been disposed of Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.975 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 52435559 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.975 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 36 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.975 [1523.1523] INFO HttpDownloader: [0xe995e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=17cb22fa458b97392e8ebd3f121f3b4c&request_ts=1774798960&track_id=52435559 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.975 [1523.1523] INFO HttpDownloader: [0xf61c18]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=52435559 Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.975 [1523.1523] INFO HttpDownloader: [0xe96e80]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ba97b5a1edc17333aa7fb36d48e43382&request_ts=1774798960&track_id=52435559 Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:40 volumio qobuz-connect[1523]: 20260329 11:42:40.975 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:40 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:40 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.983-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:40 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:40.983-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="I'm Beggin' You (Album Version)" Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.103 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 35]: Metadata became available: Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.103 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: My Kind Of Lady Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.103 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.103 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: "...Famous Last Words..." Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.103 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/ck/dz/cdbeh9a9sdzck_600.jpg Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.127 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 35]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068893&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802561&hmac=ihY6vuzmSaVr0ePpdFALQ9pYWzk Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.147 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 36]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=52435559&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802561&hmac=yuBKBRLi1a6bMPQphaAoDUuacNU Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.181 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 36]: stream information have been fetched Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 35]: stream information have been fetched Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO UrlAudioSource: [0xedf768]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068893&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802561&hmac=ihY6vuzmSaVr0ePpdFALQ9pYWzk Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO ContentFetcher: [0x11f8ff0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068893&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802561&hmac=ihY6vuzmSaVr0ePpdFALQ9pYWzk, offset: 0 Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO SampleApp: [Stream 35]: New audio stream (starting from 0ms) Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO SampleApp: [Stream 35]: Stream metadata became available: Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO SampleApp: Title: My Kind Of Lady Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO SampleApp: Album: "...Famous Last Words..." Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.189 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ck/dz/cdbeh9a9sdzck_600.jpg Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.191 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.194 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.194 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.194 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.194 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.194 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.194 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.194 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending FileAudioQualityChanged message Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.195 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.195 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending DeviceAudioQualityChanged message Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.195 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:41 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:42:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.205-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.206-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:41 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:41 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:41 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:41 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.228-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.228-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.230-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.231-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.232-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:41 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:41.233-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.250 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 36]: Metadata became available: Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.250 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: You Win, I Lose (Album Version) Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.250 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.250 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Retrospectacle - The Supertramp Anthology (Album Version) Mar 29 11:42:41 volumio qobuz-connect[1523]: 20260329 11:42:41.250 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/uc/ml/hrmfib750mluc_600.jpg Mar 29 11:42:46 volumio qobuz-connect[1523]: 20260329 11:42:46.229 [1523.1523] ERROR HttpClient: [0xe0e318]: Client finished: Timeout was reached (28) Mar 29 11:42:46 volumio qobuz-connect[1523]: 20260329 11:42:46.229 [1523.1523] INFO HttpClient: [0xe0e318]: Retrying connection (1 out of 1) Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.195 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.195 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.195 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.203-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.204-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.274 [1523.1523] ERROR HttpClient: [0xe0e318]: Client finished: Timeout was reached (28) Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] ERROR HttpClient: [0xe0e318]: Failed to connect after 1 retries Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] ERROR UrlAudioSource: [0xedf768]: Content fetcher has encountered an error Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO ContentFetcher: [0x11f8ff0]: Canceling download Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 35]: Audio stream has encountered an error Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.275 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.276 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.291-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.291-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.292-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.293-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 52435559, QID: 19, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 53111753, QID: 20, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.304 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 52435559, initial offset: 0ms Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 35]: Disposing of audio stream Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 37 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO HttpDownloader: [0xeadb60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b6c73582a60dafb6b5bbf7eac0a73890&request_ts=1774798971&track_id=52435559 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO HttpDownloader: [0xeb38b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=52435559 Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO HttpDownloader: [0xe0b548]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=31a80d2376ad1eb49a9a8baed07ccdc7&request_ts=1774798971&track_id=52435559 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 37]: Running audio stream Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO SampleApp: [Stream 35]: Audio stream has been disposed of Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 53111753 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 38 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO HttpDownloader: [0xf4fc98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=049d4db34080b9cec6d06cb7b039444c&request_ts=1774798971&track_id=53111753 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO HttpDownloader: [0xf21910]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=53111753 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO HttpDownloader: [0xeadb30]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4dad958d6b006513a5c361ee4e885346&request_ts=1774798971&track_id=53111753 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.305 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.312-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.312-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="My Kind Of Lady" Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.421 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 37]: Metadata became available: Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.421 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: You Win, I Lose (Album Version) Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.421 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.421 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Retrospectacle - The Supertramp Anthology (Album Version) Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.421 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/uc/ml/hrmfib750mluc_600.jpg Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.492 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 38]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=53111753&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802571&hmac=DwyoZpkOYxpzqSAdAnvJ7R4v540 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.501 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 37]: stream information have been fetched Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 37]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=52435559&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802571&hmac=47Za8qGD1k2B9Ivg5WLerN9vKsM Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO UrlAudioSource: [0x1237e78]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=52435559&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802571&hmac=47Za8qGD1k2B9Ivg5WLerN9vKsM Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO ContentFetcher: [0xec9b20]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=52435559&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802571&hmac=47Za8qGD1k2B9Ivg5WLerN9vKsM, offset: 0 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO SampleApp: [Stream 37]: New audio stream (starting from 0ms) Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO SampleApp: [Stream 37]: Stream metadata became available: Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO SampleApp: Title: You Win, I Lose (Album Version) Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO SampleApp: Album: Retrospectacle - The Supertramp Anthology (Album Version) Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.503 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/uc/ml/hrmfib750mluc_600.jpg Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.504 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.507 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.507 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.507 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.507 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.507 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.508 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.508 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending FileAudioQualityChanged message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.508 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.508 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending DeviceAudioQualityChanged message Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.508 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:42:51 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:51 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.511 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 38]: stream information have been fetched Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.520-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.521-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:42:51 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:42:51 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.543-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.543-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.545-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.546-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.547-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:42:51 volumio volumio5-onboarding[1259]: time=2026-03-29T11:42:51.547-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.848 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 38]: Metadata became available: Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.848 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Still In Love Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.848 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.848 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Brother Where You Bound Mar 29 11:42:51 volumio qobuz-connect[1523]: 20260329 11:42:51.848 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/ia/cy/v5g405n1xcyia_600.jpg Mar 29 11:42:56 volumio qobuz-connect[1523]: 20260329 11:42:56.519 [1523.1523] ERROR HttpClient: [0xddf0e8]: Client finished: Timeout was reached (28) Mar 29 11:42:56 volumio qobuz-connect[1523]: 20260329 11:42:56.519 [1523.1523] INFO HttpClient: [0xddf0e8]: Retrying connection (1 out of 1) Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.507 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.507 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.508 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.514-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.515-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] ERROR HttpClient: [0xddf0e8]: Client finished: Timeout was reached (28) Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] ERROR HttpClient: [0xddf0e8]: Failed to connect after 1 retries Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] ERROR UrlAudioSource: [0x1237e78]: Content fetcher has encountered an error Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] INFO ContentFetcher: [0xec9b20]: Canceling download Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 37]: Audio stream has encountered an error Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.563 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.564 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.564 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.564 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.564 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.564 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.572-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.572-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.583-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.583-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 53111753, QID: 20, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 754748, QID: 21, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 53111753, initial offset: 0ms Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 37]: Disposing of audio stream Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 39 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO HttpDownloader: [0xeadb60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=1c3bf039192768f7e2def71d97282807&request_ts=1774798981&track_id=53111753 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.593 [1523.1523] INFO HttpDownloader: [0xedf270]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=53111753 Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO HttpDownloader: [0xe0c780]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=980906f402b747e6818a70e820d9ab07&request_ts=1774798981&track_id=53111753 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 39]: Running audio stream Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO SampleApp: [Stream 37]: Audio stream has been disposed of Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 754748 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 40 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO HttpDownloader: [0xf0bc80]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8548486d122c139b7880cdd4e7dfc0a5&request_ts=1774798981&track_id=754748 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO HttpDownloader: [0xf4fa70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=754748 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO HttpDownloader: [0xefd7a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ac21836dd6cc2a4896ba72d005846b4b&request_ts=1774798981&track_id=754748 Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.594 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:01 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:01 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.602-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:01 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:01.602-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="You Win, I Lose (Album Version)" Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.725 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 39]: Metadata became available: Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.725 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Still In Love Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.725 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.725 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Brother Where You Bound Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.725 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/ia/cy/v5g405n1xcyia_600.jpg Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.759 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 39]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=53111753&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802581&hmac=yFkRCv3mJC46HbaNvoVr78SgMqY Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.849 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 40]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754748&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802581&hmac=rD5KPIwxX_NXa-O3FGzI200au8E Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.852 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 40]: Metadata became available: Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.852 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Free As A Bird (Album Version) Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.852 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.852 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Free As A Bird (Album Version) Mar 29 11:43:01 volumio qobuz-connect[1523]: 20260329 11:43:01.852 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/52/35/0060694933552_600.jpg Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.348 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 40]: stream information have been fetched Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 39]: stream information have been fetched Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO UrlAudioSource: [0xe11388]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=53111753&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802581&hmac=yFkRCv3mJC46HbaNvoVr78SgMqY Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO ContentFetcher: [0xe0c638]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=53111753&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802581&hmac=yFkRCv3mJC46HbaNvoVr78SgMqY, offset: 0 Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO SampleApp: [Stream 39]: New audio stream (starting from 0ms) Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO SampleApp: [Stream 39]: Stream metadata became available: Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO SampleApp: Title: Still In Love Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO SampleApp: Album: Brother Where You Bound Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.394 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ia/cy/v5g405n1xcyia_600.jpg Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.395 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.398 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.398 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:43:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.399 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.399 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.399 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:02 volumio qobuz-connect[1523]: 20260329 11:43:02.399 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.406-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.406-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:02 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:02 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.425-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.426-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.427-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.427-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.428-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:02 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:02.429-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:06 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.4.117 from 192.168.4.112 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 11:43:06 volumio volumio[1120]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 11:43:06 volumio volumio[1120]: info: Received Get System Info Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:43:06 volumio volumio[1120]: info: Discovery: Getting this device information Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:06 volumio volumio[1120]: info: Listing playlists Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::volumioGetQueue Mar 29 11:43:06 volumio volumio[1120]: info: CoreStateMachine::getQueue Mar 29 11:43:06 volumio volumio[1120]: info: CorePlayQueue::getQueue Mar 29 11:43:06 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 11:43:07 volumio qobuz-connect[1523]: 20260329 11:43:07.410 [1523.1523] ERROR HttpClient: [0xf87000]: Client finished: Timeout was reached (28) Mar 29 11:43:07 volumio qobuz-connect[1523]: 20260329 11:43:07.410 [1523.1523] INFO HttpClient: [0xf87000]: Retrying connection (1 out of 1) Mar 29 11:43:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.399 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.399 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.399 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.406-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.407-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] ERROR HttpClient: [0xf87000]: Client finished: Timeout was reached (28) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] ERROR HttpClient: [0xf87000]: Failed to connect after 1 retries Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] ERROR UrlAudioSource: [0xe11388]: Content fetcher has encountered an error Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] INFO ContentFetcher: [0xe0c638]: Canceling download Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 39]: Audio stream has encountered an error Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.427 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.428 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.437-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.437-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.452-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.453-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 754748, QID: 21, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 401068875, QID: 22, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.457 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 754748, initial offset: 0ms Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 39]: Disposing of audio stream Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 41 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO HttpDownloader: [0x1233528]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9ec3a3cfd4450064e81b1d7053694e91&request_ts=1774798992&track_id=754748 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO HttpDownloader: [0xedf270]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=754748 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO HttpDownloader: [0xe0c780]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b64e7d2e8b89950a01fe02c9de2aacac&request_ts=1774798992&track_id=754748 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 41]: Running audio stream Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO SampleApp: [Stream 39]: Audio stream has been disposed of Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.458 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 401068875 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.459 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 42 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.459 [1523.1523] INFO HttpDownloader: [0xe99230]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8095b0422d08e5e5c97390110a45cd9e&request_ts=1774798992&track_id=401068875 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.459 [1523.1523] INFO HttpDownloader: [0xefd510]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401068875 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.459 [1523.1523] INFO HttpDownloader: [0x1163440]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=499396e1521332c2335bcef7006d2522&request_ts=1774798992&track_id=401068875 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.459 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.471-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.471-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Still In Love" Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.570 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 41]: Metadata became available: Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.570 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Free As A Bird (Album Version) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.570 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.570 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Free As A Bird (Album Version) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.570 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/52/35/0060694933552_600.jpg Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.613 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 41]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754748&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802592&hmac=t95mUSpBnj3rgoKaxaUAWbm8FAk Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.640 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 42]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068875&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802592&hmac=3MoSQRnIFhV3e2INuouL_4vY6NM Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.674 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 41]: stream information have been fetched Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.674 [1523.1523] INFO UrlAudioSource: [0x1020940]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754748&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802592&hmac=t95mUSpBnj3rgoKaxaUAWbm8FAk Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.674 [1523.1523] INFO ContentFetcher: [0x11f3788]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=754748&fmt=6&profile=raw&app_id=174516466&cid=2332902&etsp=1774802592&hmac=t95mUSpBnj3rgoKaxaUAWbm8FAk, offset: 0 Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.674 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.674 [1523.1523] INFO SampleApp: [Stream 41]: New audio stream (starting from 0ms) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.675 [1523.1523] INFO SampleApp: [Stream 41]: Stream metadata became available: Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.675 [1523.1523] INFO SampleApp: Title: Free As A Bird (Album Version) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.675 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.675 [1523.1523] INFO SampleApp: Album: Free As A Bird (Album Version) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.675 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/52/35/0060694933552_600.jpg Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.676 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.678 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.679 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.679 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.679 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.679 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.679 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:43:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:12 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:12 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.693 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 42]: Metadata became available: Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.693 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Oh Darling (Remastered 2026) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.693 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.693 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Breakfast In America (Remastered 2026) Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.693 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/te/rc/cdw6pcmgyrcte_600.jpg Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.697-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.697-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Free As A Bird (Album Version)" Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.701-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.702-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Free As A Bird (Album Version)" Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.705-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:12 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:12.705-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Free As A Bird (Album Version)" Mar 29 11:43:12 volumio qobuz-connect[1523]: 20260329 11:43:12.717 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 42]: stream information have been fetched Mar 29 11:43:13 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 11:43:17 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 11:43:17 volumio qobuz-connect[1523]: 20260329 11:43:17.715 [1523.1523] ERROR HttpClient: [0x10233b8]: Client finished: Timeout was reached (28) Mar 29 11:43:17 volumio qobuz-connect[1523]: 20260329 11:43:17.715 [1523.1523] INFO HttpClient: [0x10233b8]: Retrying connection (1 out of 1) Mar 29 11:43:18 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:20 volumio volumio[1120]: info: Executing endpoint metavolumio Mar 29 11:43:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 11:43:20 volumio volumio[1120]: info: Executing endpoint metavolumio Mar 29 11:43:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 11:43:20 volumio volumio[1120]: info: Executing endpoint metavolumio Mar 29 11:43:20 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.679 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.679 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.679 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.686-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.687-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Free As A Bird (Album Version)" Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] ERROR HttpClient: [0x10233b8]: Client finished: Timeout was reached (28) Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] ERROR HttpClient: [0x10233b8]: Failed to connect after 1 retries Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] ERROR UrlAudioSource: [0x1020940]: Content fetcher has encountered an error Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO ContentFetcher: [0x11f3788]: Canceling download Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] ERROR AudioStreamManager: [0xdd75f8]: [Stream 41]: Audio stream has encountered an error Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] ERROR MediaEngine: [0xdd7548]: Current stream has encountered an error Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending PlaybackError message Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO SampleApp: Playback state changed: Stopped Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming ended event Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.757 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.758 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.758 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Stopping audio renderer Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.758 [1523.1523] INFO SampleApp: Stopping playback Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.769-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.769-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Free As A Bird (Album Version)" Mar 29 11:43:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Received SetState message: Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playing state: Playing Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Playback position: 0 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Queue version: 14.2 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Current track: TID: 401068875, QID: 22, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Next track: TID: 112884228, QID: 23, Context UUID: b8a5b556-fae3-48bd-99b0-e4db66d3d48f Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO MediaEngine: [0xdd7548]: Reseting Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO MediaEngine: [0xdd7548]: Stopping playback, clearing tracks Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO MediaEngine: [0xdd7548]: Initiating playback Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.787 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting current track: 401068875, initial offset: 0ms Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Clearing all streams Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 41]: Disposing of audio stream Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 43 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO HttpDownloader: [0xecca60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8c93465c5d506220f900610d27e0f196&request_ts=1774799002&track_id=401068875 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO HttpDownloader: [0x1233528]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401068875 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO HttpDownloader: [0x10bf7c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=65886af106997e00e152e46a387fde22&request_ts=1774799002&track_id=401068875 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 43]: Running audio stream Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO SampleApp: [Stream 41]: Audio stream has been disposed of Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO MediaEngine: [0xdd7548]: Setting next track: 112884228 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: New stream: 44 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO HttpDownloader: [0xf26b80]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=acbdd0cc4cb362a56e4556b0ae4ae79a&request_ts=1774799002&track_id=112884228 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO HttpDownloader: [0xef5e60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=112884228 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO HttpDownloader: [0xf16ca8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=7f8929743f81f898a280cbe0c56baee0&request_ts=1774799002&track_id=112884228 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.788 [1523.1523] INFO MediaEngine: [0xdd7548]: Waiting for current stream to start before starting audio renderer Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.789-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.789-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Free As A Bird (Album Version)" Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:22 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.809-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:22 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:22.809-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Free As A Bird (Album Version)" Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.916 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 43]: Metadata became available: Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.916 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Oh Darling (Remastered 2026) Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.916 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.916 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Breakfast In America (Remastered 2026) Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.916 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/te/rc/cdw6pcmgyrcte_600.jpg Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.936 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 43]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068875&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802602&hmac=8x_TojU-Rnu3RXWy4ErSR7wpuDs Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.964 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 44]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=112884228&fmt=7&profile=raw&app_id=174516466&cid=2332902&etsp=1774802602&hmac=LwyaTTk2t3kNANafVz0-eJvlVX0 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.982 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 44]: Metadata became available: Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.982 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Title: Lover Boy Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.982 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Artist: Supertramp Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.982 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album: Even In The Quietest Moments... Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.982 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: Album art URL: https://static.qobuz.com/images/covers/db/hm/pt87el6f1hmdb_600.jpg Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.997 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 43]: stream information have been fetched Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.997 [1523.1523] INFO UrlAudioSource: [0x11f6d18]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068875&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802602&hmac=8x_TojU-Rnu3RXWy4ErSR7wpuDs Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.997 [1523.1523] INFO ContentFetcher: [0xe0e318]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1017401&eid=401068875&fmt=27&profile=raw&app_id=174516466&cid=2332902&etsp=1774802602&hmac=8x_TojU-Rnu3RXWy4ErSR7wpuDs, offset: 0 Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.998 [1523.1523] INFO AudioRenderer: [0xdd76b0]: Starting audio renderer, initial playback state: Playing Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.998 [1523.1523] INFO SampleApp: [Stream 43]: New audio stream (starting from 0ms) Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.998 [1523.1523] INFO SampleApp: [Stream 43]: Stream metadata became available: Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.998 [1523.1523] INFO SampleApp: Title: Oh Darling (Remastered 2026) Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.998 [1523.1523] INFO SampleApp: Artist: Supertramp Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.998 [1523.1523] INFO SampleApp: Album: Breakfast In America (Remastered 2026) Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.998 [1523.1523] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/te/rc/cdw6pcmgyrcte_600.jpg Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:22 volumio volumio[1120]: info: QobuzConnect: alread currrent service, send playback confirmation Mar 29 11:43:22 volumio qobuz-connect[1523]: 20260329 11:43:22.999 [1523.1523] INFO SampleApp: Initiating playback, initial state: Playing Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO SampleApp: Playback state changed: Playing Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO StreamingEventReporter: [0xdd7ed0]: Reporting streaming started event Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO HttpDownloader: [0xdd7f28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO ProtocolHandler: [0xdd7b20]: Need to send state update Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending StateUpdated message Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending FileAudioQualityChanged message Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.002 [1523.1523] INFO QConnectMessageSender: [0xeb4568]: Sending DeviceAudioQualityChanged message Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.003 [1523.1523] INFO QwspMessageSender: [0xeb4558]: Sending Payload message Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:23 volumio qobuz-connect[1523]: 20260329 11:43:23.009 [1523.1523] INFO AudioStreamManager: [0xdd75f8]: [Stream 44]: stream information have been fetched Mar 29 11:43:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Mar 29 11:43:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.010-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.011-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Oh Darling (Remastered 2026)" Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreStateMachine::pushState Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output update for this device Mar 29 11:43:23 volumio volumio[1120]: info: MRS: Pushing multiroomSync output Mar 29 11:43:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.029-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.029-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Oh Darling (Remastered 2026)" Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.031-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.031-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Oh Darling (Remastered 2026)" Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.032-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 11:43:23 volumio volumio5-onboarding[1259]: time=2026-03-29T11:43:23.033-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.112:52862,192.168.4.112:52863 @ 0x3000d50" id= title="Oh Darling (Remastered 2026)" Mar 29 11:43:24 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 11:43:26 volumio volumio[1120]: info: Retrieving Cloud Streaming UI Mar 29 11:43:26 volumio volumio[1120]: info: Getting Tidal Cloud Configuration Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 11:43:26 volumio volumio[1120]: info: Getting Qobuz Cloud Configuration Mar 29 11:43:26 volumio volumio[1120]: info: Asking plugin for UI Config Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 11:43:26 volumio volumio[1120]: info: Getting Spotify Cloud Configuration Mar 29 11:43:26 volumio volumio[1120]: info: Asking plugin for UI Config Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 11:43:26 volumio volumio[1120]: info: Saving Spotify Acccount Mar 29 11:43:26 volumio volumio[1120]: info: Got it Mar 29 11:43:26 volumio volumio[1120]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 11:43:26 volumio volumio[1120]: info: Got Tidal Cloud Configuration Mar 29 11:43:26 volumio volumio[1120]: info: Got it Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 11:43:26 volumio volumio[1120]: info: Executing endpoint metavolumio Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 11:43:26 volumio volumio[1120]: info: Executing endpoint metavolumio Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 11:43:26 volumio volumio[1120]: info: Executing endpoint metavolumio Mar 29 11:43:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 11:43:28 volumio qobuz-connect[1523]: 20260329 11:43:28.015 [1523.1523] ERROR HttpClient: [0xe10350]: Client finished: Timeout was reached (28) Mar 29 11:43:28 volumio qobuz-connect[1523]: 20260329 11:43:28.015 [1523.1523] INFO HttpClient: [0xe10350]: Retrying connection (1 out of 1) Mar 29 11:43:30 volumio volumio[1120]: info: Disabling MyMusic plugin upnp Mar 29 11:43:30 volumio sudo[2412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 29 11:43:30 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 11:43:30 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 29 11:43:30 volumio volumio[1120]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 11:43:30 volumio volumio[1120]: error: Upnp client error: Error: This socket has been ended by the other party Mar 29 11:43:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 11:43:31 volumio volumio[1120]: info: Enabling MyMusic plugin upnp Mar 29 11:43:31 volumio volumio[1120]: info: Enabling plugin upnp Mar 29 11:43:31 volumio volumio[1120]: info: Loading plugin "upnp"... Mar 29 11:43:31 volumio volumio[1120]: info: [1774799011124] Starting Upmpd Daemon Mar 29 11:43:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 11:43:31 volumio volumio[1120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 11:43:31 volumio volumio[1120]: Error: listen EADDRINUSE: address already in use :::6599 Mar 29 11:43:31 volumio volumio[1120]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Mar 29 11:43:31 volumio volumio[1120]: at listenInCluster (net.js:1366:12) Mar 29 11:43:31 volumio volumio[1120]: at Server.listen (net.js:1452:7) Mar 29 11:43:31 volumio volumio[1120]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 29 11:43:31 volumio volumio[1120]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 29 11:43:31 volumio volumio[1120]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 29 11:43:31 volumio volumio[1120]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 29 11:43:31 volumio volumio[1120]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 29 11:43:31 volumio volumio[1120]: code: 'EADDRINUSE', Mar 29 11:43:31 volumio volumio[1120]: errno: -98, Mar 29 11:43:31 volumio volumio[1120]: syscall: 'listen', Mar 29 11:43:31 volumio volumio[1120]: address: '::', Mar 29 11:43:31 volumio volumio[1120]: port: 6599 Mar 29 11:43:31 volumio volumio[1120]: } Mar 29 11:43:31 volumio volumio[1120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 11:43:31 volumio sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 11:42 Mar 29 11:43:31 volumio sudo[2423]: 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="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"