Oct 14 23:10:07 volumio qobuz-connect[3057]: 20261014 23:10:07.033 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:10:07 volumio qobuz-connect[3057]: 20261014 23:10:07.033 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:10:07 volumio qobuz-connect[3057]: 20261014 23:10:07.033 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:10:07 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:07 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:07 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:07 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:10:07 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:07 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:07 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:07 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:07 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:07.037+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PLAYING positionMs=230002 volume=100 Oct 14 23:10:07 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:07.037+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Astolfo (Bonus Track)" Oct 14 23:10:07 volumio volumio[1357]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:07 volumio volumio[1357]: info: Display-configuration --- → Wakeup triggered Oct 14 23:10:07 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:07 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 265 | title: Astolfo (Bonus Track) | previous title: Astolfo (Bonus Track) Oct 14 23:10:07 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:07 volumio volumio[1357]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 14 23:10:07 volumio volumio[1357]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 14 23:10:07 volumio volumio[1357]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 14 23:10:15 volumio ntpd[1210]: PROTO: 95.110.254.234 unlink local addr 192.168.1.244 -> Oct 14 23:10:17 volumio qobuz-connect[3057]: 20261014 23:10:17.034 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:10:17 volumio qobuz-connect[3057]: 20261014 23:10:17.034 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:10:17 volumio qobuz-connect[3057]: 20261014 23:10:17.034 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:10:17 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:17 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:17 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:17 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:10:17 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:17 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:17 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:17 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:17 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:17.038+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PLAYING positionMs=240003 volume=100 Oct 14 23:10:17 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:17.038+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Astolfo (Bonus Track)" Oct 14 23:10:17 volumio volumio[1357]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:17 volumio volumio[1357]: info: Display-configuration --- → Wakeup triggered Oct 14 23:10:17 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:17 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 265 | title: Astolfo (Bonus Track) | previous title: Astolfo (Bonus Track) Oct 14 23:10:17 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:17 volumio volumio[1357]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 14 23:10:17 volumio volumio[1357]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 14 23:10:17 volumio volumio[1357]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 14 23:10:27 volumio qobuz-connect[3057]: 20261014 23:10:27.035 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:10:27 volumio qobuz-connect[3057]: 20261014 23:10:27.035 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:10:27 volumio qobuz-connect[3057]: 20261014 23:10:27.035 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:10:27 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:27 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:27 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:27 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:10:27 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:27 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:27 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:27 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:27 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:27.038+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PLAYING positionMs=250004 volume=100 Oct 14 23:10:27 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:27.038+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Astolfo (Bonus Track)" Oct 14 23:10:27 volumio volumio[1357]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:27 volumio volumio[1357]: info: Display-configuration --- → Wakeup triggered Oct 14 23:10:27 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:27 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 265 | title: Astolfo (Bonus Track) | previous title: Astolfo (Bonus Track) Oct 14 23:10:27 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:27 volumio volumio[1357]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 14 23:10:27 volumio volumio[1357]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 14 23:10:27 volumio volumio[1357]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 14 23:10:36 volumio qobuz-connect[3057]: 20261014 23:10:36.803 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 40]: Audio stream has finished Oct 14 23:10:36 volumio qobuz-connect[3057]: 20261014 23:10:36.803 [3057.3057] INFO SampleApp: [Stream 40]: Audio stream finished Oct 14 23:10:37 volumio qobuz-connect[3057]: 20261014 23:10:37.036 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:10:37 volumio qobuz-connect[3057]: 20261014 23:10:37.036 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:10:37 volumio qobuz-connect[3057]: 20261014 23:10:37.036 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:10:37 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:37 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:37 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:37 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:10:37 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:37 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:37 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:37 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:37 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:37.039+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PLAYING positionMs=260004 volume=100 Oct 14 23:10:37 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:37.039+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Astolfo (Bonus Track)" Oct 14 23:10:37 volumio volumio[1357]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:37 volumio volumio[1357]: info: Display-configuration --- → Wakeup triggered Oct 14 23:10:37 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:37 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 265 | title: Astolfo (Bonus Track) | previous title: Astolfo (Bonus Track) Oct 14 23:10:37 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:37 volumio volumio[1357]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 14 23:10:37 volumio volumio[1357]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 14 23:10:37 volumio volumio[1357]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 14 23:10:38 volumio qobuz-connect[3057]: 20261014 23:10:38.098 [3057.3057] INFO SampleApp: Playback is nearly finished Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.099 [3057.3057] INFO SampleApp: Playback has finished Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.099 [3057.3057] INFO SampleApp: Nothing else to play, stopping playback Oct 14 23:10:41 volumio qobuz-connect[3057]: ALSA lib ./src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 40]: Disposing of audio stream Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO ContentFetcher: [0x55bde65f5830]: Canceling download Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO SampleApp: [Stream 40]: Audio stream has been disposed of Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO StreamingEventReporter: [0x55bde655f690]: Reporting streaming ended event Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO HttpDownloader: [0x55bde655f720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.100 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.102 [3057.3057] INFO SampleApp: Playback state changed: Stopped Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.105+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PAUSED positionMs=260004 volume=100 Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.105+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Astolfo (Bonus Track)" Oct 14 23:10:41 volumio volumio[1357]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:41 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 265 | title: Astolfo (Bonus Track) | previous title: Astolfo (Bonus Track) Oct 14 23:10:41 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] Song has been paused, so also pausing timer. Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Received SetState message: Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Playing state: Paused Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Playback position: Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Queue version: 11.1 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Current track: TID: 98076410, QID: 0, Context UUID: 266fd9c1-4344-4f3a-a9fb-986accc0fa58 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Next track: TID: 98076411, QID: 1, Context UUID: 266fd9c1-4344-4f3a-a9fb-986accc0fa58 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Stopping playback, clearing tracks Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Initiating playback Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO RendererActionAvailabilityManager: [0x55bde655eea0]: Renderer action 'Next' is available Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Setting current track: 98076410, initial offset: 0ms Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Clearing all streams Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: New stream: 41 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO HttpDownloader: [0x55bde6b933d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=482115fa6de1f726a71032184f73805d&request_ts=1792012241&track_id=98076410 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO HttpDownloader: [0x55bde69a7040]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=98076410 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO HttpDownloader: [0x55bde675f5f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=91161b79abed18b949c91c9aa6bbfbff&request_ts=1792012241&track_id=98076410 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 41]: Running audio stream Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Setting next track: 98076411 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: New stream: 42 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO HttpDownloader: [0x55bde668d390]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=39c82512140ae5b0057b3f5b1015d5db&request_ts=1792012241&track_id=98076411 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO HttpDownloader: [0x55bde66991e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=98076411 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO HttpDownloader: [0x55bde66ccc30]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a548f2659d566cfdc7abbbc91adeb8ca&request_ts=1792012241&track_id=98076411 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.158 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Waiting for current stream to start before starting audio renderer Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.371 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 42]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=98076411&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1792015841&hmac=jn4hGwfKfhk3HTc6znhPxMRGnsI Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.391 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 42]: Metadata became available: Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.391 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Title: Mentre Tutto Scorre Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.391 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Artist: Negramaro Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.392 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album: Mentre Tutto Scorre Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.392 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album art URL: https://static.qobuz.com/images/covers/ob/2e/xct1qyutd2eob_600.jpg Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.398 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 41]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=98076410&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1792015841&hmac=4DXugTtV2ECwDMflA5rZgDMkGDE Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.416 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 42]: stream information have been fetched Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.425 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 41]: stream information have been fetched Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.425 [3057.3057] INFO UrlAudioSource: [0x55bde6b69930]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=98076410&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1792015841&hmac=4DXugTtV2ECwDMflA5rZgDMkGDE Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.425 [3057.3057] INFO ContentFetcher: [0x55bde68f8e40]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=98076410&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1792015841&hmac=4DXugTtV2ECwDMflA5rZgDMkGDE, offset: 0 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.425 [3057.3057] INFO AudioRenderer: [0x55bde655e7d0]: Starting audio renderer, initial playback state: Paused Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.425 [3057.3057] INFO SampleApp: [Stream 41]: New audio stream (starting from 0ms) Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio[1357]: info: QobuzConnect: alread currrent service, send playback confirmation Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.426 [3057.3057] INFO SampleApp: Initiating playback, initial state: Paused Oct 14 23:10:41 volumio qobuz-connect[3057]: ALSA lib ./src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Oct 14 23:10:41 volumio qobuz-connect[3057]: ALSA lib ./src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.429 [3057.3057] INFO SampleApp: Playback state changed: Paused Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.429 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.429 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.429 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.433+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PAUSED positionMs=0 volume=100 Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.433+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title= Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.433+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PAUSED positionMs=260004 volume=100 Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.433+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Astolfo (Bonus Track)" Oct 14 23:10:41 volumio volumio[1357]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:41 volumio volumio[1357]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 41]: Metadata became available: Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Title: Nella Mia Stanza Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Artist: Negramaro Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album: Mentre Tutto Scorre Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album art URL: https://static.qobuz.com/images/covers/ob/2e/xct1qyutd2eob_600.jpg Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO SampleApp: [Stream 41]: Stream metadata became available: Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO SampleApp: Title: Nella Mia Stanza Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO SampleApp: Artist: Negramaro Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO SampleApp: Album: Mentre Tutto Scorre Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.437 [3057.3057] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ob/2e/xct1qyutd2eob_600.jpg Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:41 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 265 | title: Astolfo (Bonus Track) | previous title: Astolfo (Bonus Track) Oct 14 23:10:41 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] Song has been paused, so also pausing timer. Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:41 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 216 | title: | previous title: Astolfo (Bonus Track) Oct 14 23:10:41 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] Song has been paused, so also pausing timer. Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:10:41 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:10:41 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.443+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PAUSED positionMs=0 volume=100 Oct 14 23:10:41 volumio volumio5-onboarding[2039]: time=2026-10-14T23:10:41.443+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Nella Mia Stanza" Oct 14 23:10:41 volumio volumio[1357]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:10:41 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 216 | title: Nella Mia Stanza | previous title: Astolfo (Bonus Track) Oct 14 23:10:41 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:10:41 volumio volumio[1357]: info: [LastFM] Song has been paused, so also pausing timer. Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.3057] INFO UrlAudioSource: [0x55bde6b69930]: Content info extracted Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.3057] INFO UrlAudioSource: [0x55bde6b69930]: Size: 26612718 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.73819] INFO AudioDecoder: [0x55bde65eced0]: Stream info: Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.73819] INFO AudioDecoder: [0x55bde65eced0]: Total samples: 9484440 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.73819] INFO AudioDecoder: [0x55bde65eced0]: Sample rate: 44100 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.73819] INFO AudioDecoder: [0x55bde65eced0]: Channel count: 2 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.73819] INFO AudioDecoder: [0x55bde65eced0]: Bits per sample: 16 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.73819] INFO AudioDecoder: [0x55bde65eced0]: Duration: 215066 Oct 14 23:10:41 volumio qobuz-connect[3057]: 20261014 23:10:41.486 [3057.3057] INFO UrlAudioSource: [0x55bde6b69930]: Audio properties have changed Oct 14 23:10:43 volumio volumio[1357]: info: Executing endpoint metavolumio Oct 14 23:10:43 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 23:10:43 volumio volumio[1357]: info: Executing endpoint metavolumio Oct 14 23:10:43 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 23:10:43 volumio volumio[1357]: info: Executing endpoint metavolumio Oct 14 23:10:43 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Received SetState message: Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Playing state: Playing Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Playback position: 0 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Queue version: 12.1 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Current track: TID: 17110879, QID: 0, Context UUID: 93f58998-3cd0-4503-a428-ac3bb26b4051 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Next track: TID: 20260077, QID: 1, Context UUID: 93f58998-3cd0-4503-a428-ac3bb26b4051 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Stopping playback, clearing tracks Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Initiating playback Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO AudioRenderer: [0x55bde655e7d0]: Stopping audio renderer Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.948 [3057.3057] INFO SampleApp: Stopping playback Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.949 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Clearing all streams Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.949 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 41]: Disposing of audio stream Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.949 [3057.3057] INFO ContentFetcher: [0x55bde68f8e40]: Canceling download Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO SampleApp: Playback state changed: Stopped Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO SampleApp: [Stream 41]: Audio stream has been disposed of Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Setting current track: 17110879, initial offset: 0ms Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Clearing all streams Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: New stream: 43 Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:11:49 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO HttpDownloader: [0x55bde67af330]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=801a6a2b49c49fc10d5edc712c28b34f&request_ts=1792012309&track_id=17110879 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO HttpDownloader: [0x55bde6b930e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=17110879 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO HttpDownloader: [0x55bde66ac560]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=995f30da0d95fe803292386852ef18cb&request_ts=1792012309&track_id=17110879 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 43]: Running audio stream Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Setting next track: 20260077 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: New stream: 44 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.950 [3057.3057] INFO HttpDownloader: [0x55bde68bfe20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=5bc3284a3aedaca290d47ad87391ecf9&request_ts=1792012309&track_id=20260077 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.951 [3057.3057] INFO HttpDownloader: [0x55bde68b6890]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=20260077 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.951 [3057.3057] INFO HttpDownloader: [0x55bde6d1e0b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=fdaacf0851293d1fb025fa445bd6c1dc&request_ts=1792012309&track_id=20260077 Oct 14 23:11:49 volumio qobuz-connect[3057]: 20261014 23:11:49.951 [3057.3057] INFO MediaEngine: [0x55bde655e550]: Waiting for current stream to start before starting audio renderer Oct 14 23:11:49 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:11:49 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:11:49 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:11:49 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:11:49 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:11:49 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:49 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:49.954+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PAUSED positionMs=0 volume=100 Oct 14 23:11:49 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:49.954+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PAUSED positionMs=0 volume=100 Oct 14 23:11:49 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:49.954+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Nella Mia Stanza" Oct 14 23:11:49 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:49.954+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title="Nella Mia Stanza" Oct 14 23:11:49 volumio volumio[1357]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 14 23:11:49 volumio volumio[1357]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 14 23:11:49 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:11:49 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 216 | title: Nella Mia Stanza | previous title: Astolfo (Bonus Track) Oct 14 23:11:49 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:11:49 volumio volumio[1357]: info: [LastFM] Song has been paused, so also pausing timer. Oct 14 23:11:49 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:11:49 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 216 | title: Nella Mia Stanza | previous title: Astolfo (Bonus Track) Oct 14 23:11:49 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:11:49 volumio volumio[1357]: info: [LastFM] Song has been paused, so also pausing timer. Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.065 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 44]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=20260077&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1792015910&hmac=ROWLkvSMfkr7Gt1GDV14GGa_ZbQ Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.085 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 43]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=17110879&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1792015910&hmac=2y-bDOISpnXR23xSvPT1YiPmbBY Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.128 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 44]: Metadata became available: Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.128 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Title: Stairway to Heaven (Remaster) Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.128 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Artist: Led Zeppelin Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.128 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album: Led Zeppelin IV (HD Remastered Edition) Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.128 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album art URL: https://static.qobuz.com/images/covers/03/85/0603497898503_600.jpg Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.156 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 43]: stream information have been fetched Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.156 [3057.3057] INFO UrlAudioSource: [0x55bde67417a0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=17110879&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1792015910&hmac=2y-bDOISpnXR23xSvPT1YiPmbBY Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.156 [3057.3057] INFO ContentFetcher: [0x55bde6d81d30]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=17110879&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1792015910&hmac=2y-bDOISpnXR23xSvPT1YiPmbBY, offset: 0 Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.156 [3057.3057] INFO AudioRenderer: [0x55bde655e7d0]: Starting audio renderer, initial playback state: Playing Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.156 [3057.3057] INFO SampleApp: [Stream 43]: New audio stream (starting from 0ms) Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio[1357]: info: QobuzConnect: alread currrent service, send playback confirmation Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.156 [3057.3057] INFO SampleApp: Initiating playback, initial state: Playing Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.159 [3057.3057] INFO SampleApp: Playback state changed: Playing Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.159 [3057.3057] INFO StreamingEventReporter: [0x55bde655f690]: Reporting streaming started event Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.159 [3057.3057] INFO HttpDownloader: [0x55bde655f720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.159 [3057.3057] INFO ProtocolHandler: [0x55bde655efc0]: Need to send state update Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.159 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending StateUpdated message Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.159 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.159 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending FileAudioQualityChanged message Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.160 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.160 [3057.3057] INFO QConnectMessageSender: [0x55bde6c03770]: Sending DeviceAudioQualityChanged message Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.160 [3057.3057] INFO QwspMessageSender: [0x55bde6c03750]: Sending Payload message Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.163+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PAUSED positionMs=0 volume=100 Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.163+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.164+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title= Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.164+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title= Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::servicePushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreStateMachine::pushState Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioPushState Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output update for this device Oct 14 23:11:50 volumio volumio[1357]: info: MRS: Pushing multiroomSync output Oct 14 23:11:50 volumio volumio[1357]: info: CoreCommandRouter::volumioGetState Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.168+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.168+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.168+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title= Oct 14 23:11:50 volumio volumio5-onboarding[2039]: time=2026-10-14T23:11:50.168+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:43900 @ 0xc000604870" id= title= Oct 14 23:11:50 volumio volumio[1357]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 14 23:11:50 volumio volumio[1357]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 14 23:11:50 volumio volumio[1357]: info: Display-configuration --- → Wakeup triggered Oct 14 23:11:50 volumio volumio[1357]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 43]: Metadata became available: Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Title: Whole Lotta Love (Remaster) Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Artist: Led Zeppelin Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album: Led Zeppelin II (Remaster) Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: Album art URL: https://static.qobuz.com/images/covers/99/00/0603497920099_600.jpg Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO SampleApp: [Stream 43]: Stream metadata became available: Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO SampleApp: Title: Whole Lotta Love (Remaster) Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO SampleApp: Artist: Led Zeppelin Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO SampleApp: Album: Led Zeppelin II (Remaster) Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.175 [3057.3057] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/99/00/0603497920099_600.jpg Oct 14 23:11:50 volumio volumio[1357]: info: Display-configuration --- → Wakeup triggered Oct 14 23:11:50 volumio volumio[1357]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 14 23:11:50 volumio volumio[1357]: info: Display-configuration --- → Wakeup triggered Oct 14 23:11:50 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:11:50 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 335 | title: | previous title: Astolfo (Bonus Track) Oct 14 23:11:50 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:11:50 volumio volumio[1357]: info: [LastFM] Song has been paused, so also pausing timer. Oct 14 23:11:50 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:11:50 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 335 | title: | previous title: Astolfo (Bonus Track) Oct 14 23:11:50 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:11:50 volumio volumio[1357]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 14 23:11:50 volumio volumio[1357]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Oct 14 23:11:50 volumio volumio[1357]: info: [LastFM] using existing timer Oct 14 23:11:50 volumio volumio[1357]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 335 | title: undefined | previous title: Oct 14 23:11:50 volumio volumio[1357]: info: =================> [timer] is active: false | can continue: false | timer started at: 1792011977068 Oct 14 23:11:50 volumio volumio[1357]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 14 23:11:50 volumio volumio[1357]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 23:11:50 volumio volumio[1357]: TypeError: Cannot read properties of undefined (reading 'indexOf') Oct 14 23:11:50 volumio volumio[1357]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Oct 14 23:11:50 volumio volumio[1357]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Oct 14 23:11:50 volumio volumio[1357]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Oct 14 23:11:50 volumio volumio[1357]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 14 23:11:50 volumio volumio[1357]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Oct 14 23:11:50 volumio volumio[1357]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Oct 14 23:11:50 volumio volumio[1357]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 14 23:11:50 volumio volumio[1357]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 14 23:11:50 volumio volumio[1357]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Oct 14 23:11:50 volumio volumio[1357]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 14 23:11:50 volumio volumio[1357]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Oct 14 23:11:50 volumio volumio[1357]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Oct 14 23:11:50 volumio volumio[1357]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Oct 14 23:11:50 volumio volumio[1357]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 14 23:11:50 volumio volumio[1357]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 14 23:11:50 volumio volumio[1357]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Oct 14 23:11:50 volumio volumio[1357]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 23:11:50 volumio qobuz-connect[3057]: 20261014 23:11:50.266 [3057.3057] INFO AudioStreamManager: [0x55bde655e680]: [Stream 44]: stream information have been fetched Oct 14 23:11:50 volumio sudo[74025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 23:10' Oct 14 23:11:50 volumio sudo[74025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"