Oct 11 23:30:06 volumio qobuz-connect[2581]: 20261011 23:30:06.754 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:30:06 volumio qobuz-connect[2581]: 20261011 23:30:06.754 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:30:06 volumio qobuz-connect[2581]: 20261011 23:30:06.754 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:30:06 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:06 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:30:06 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:30:06 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:06 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:06 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:30:06 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:30:06 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:06 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:30:06 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:30:06 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:30:06 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:30:06 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:30:06 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:30:06 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:30:06 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:30:16 volumio qobuz-connect[2581]: 20261011 23:30:16.753 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:30:16 volumio qobuz-connect[2581]: 20261011 23:30:16.753 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:30:16 volumio qobuz-connect[2581]: 20261011 23:30:16.753 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:30:16 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:16 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:30:16 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:30:16 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:16 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:16 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:30:16 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:30:16 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:16 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:30:16 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:30:16 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:30:16 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:30:16 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:30:16 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:30:16 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:30:16 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:30:26 volumio qobuz-connect[2581]: 20261011 23:30:26.753 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:30:26 volumio qobuz-connect[2581]: 20261011 23:30:26.753 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:30:26 volumio qobuz-connect[2581]: 20261011 23:30:26.753 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:30:26 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:26 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:30:26 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:30:26 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:26 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:26 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:30:26 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:30:26 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:26 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:30:26 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:30:26 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:30:26 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:30:26 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:30:26 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:30:26 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:30:26 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:30:36 volumio qobuz-connect[2581]: 20261011 23:30:36.753 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:30:36 volumio qobuz-connect[2581]: 20261011 23:30:36.753 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:30:36 volumio qobuz-connect[2581]: 20261011 23:30:36.753 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:30:36 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:36 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:30:36 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:30:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:36 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:36 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:30:36 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:30:36 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:36 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:30:36 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:30:36 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:30:36 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:30:36 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:30:36 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:30:36 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:30:36 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:30:46 volumio qobuz-connect[2581]: 20261011 23:30:46.753 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:30:46 volumio qobuz-connect[2581]: 20261011 23:30:46.753 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:30:46 volumio qobuz-connect[2581]: 20261011 23:30:46.753 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:30:46 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:46 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:30:46 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:30:46 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:46 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:46 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:30:46 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:30:46 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:46 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:30:46 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:30:46 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:30:46 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:30:46 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:30:46 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:30:46 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:30:46 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:30:56 volumio qobuz-connect[2581]: 20261011 23:30:56.754 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:30:56 volumio qobuz-connect[2581]: 20261011 23:30:56.754 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:30:56 volumio qobuz-connect[2581]: 20261011 23:30:56.754 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:30:56 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:56 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:30:56 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:30:56 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:30:56 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:30:56 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:30:56 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:30:56 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:30:56 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:30:56 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:30:56 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:30:56 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:30:56 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:30:56 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:30:56 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:30:56 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Received SetState message: Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playing state: Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playback position: 295811 Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Queue version: Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Current track: Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Next track: Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Seeking to time offset: 295811ms Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Seek is in progress Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 17]: Seeking to time offset: 295811ms Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO UrlAudioSource: [0x55c0aa390a40]: Seeking URL audio source to time offset: 295811ms Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.364 [2581.2581] INFO SampleApp: Seek is in progress Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.375 [2581.2581] INFO UrlAudioSource: [0x55c0aa390a40]: Seeking input stream to byte offset: 85351173 Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.375 [2581.2581] INFO ContentFetcher: [0x55c0aa8f5d30]: Adjusting download offset to: 85351173 Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.376 [2581.2581] INFO SampleApp: Playback state changed: Seeking Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.376 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming ended event Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.376 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.376 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.376 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.376 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:01 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:01 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.467 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Current stream has been seeked Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.468 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Seek has finished Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.468 [2581.2581] INFO SampleApp: [Stream 17]: Audio stream seeked: 295811ms Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.468 [2581.2581] INFO SampleApp: Playback state changed: Playing Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.468 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming started event Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.468 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.468 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.468 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:01 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:01 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:01 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:01 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:01 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:01 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:01 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:01 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:01 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:01 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:01 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:01 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:01 volumio qobuz-connect[2581]: 20261011 23:31:01.560 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 11 23:31:11 volumio qobuz-connect[2581]: 20261011 23:31:11.468 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:11 volumio qobuz-connect[2581]: 20261011 23:31:11.468 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:11 volumio qobuz-connect[2581]: 20261011 23:31:11.468 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:11 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:11 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:11 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:11 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:11 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:11 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:11 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:11 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:11 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:11 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:11 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:11 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:11 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:11 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:11 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:11 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:21 volumio qobuz-connect[2581]: 20261011 23:31:21.468 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:21 volumio qobuz-connect[2581]: 20261011 23:31:21.468 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:21 volumio qobuz-connect[2581]: 20261011 23:31:21.468 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:21 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:21 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:21 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:21 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:21 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:21 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:21 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:21 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:21 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:21 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:21 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:21 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:21 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:21 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:21 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:21 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:29 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 11 23:31:29 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 11 23:31:29 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Received SetState message: Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playing state: Paused Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playback position: Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Queue version: Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Current track: Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Next track: Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Pausing playback Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Pausing audio renderer Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO SampleApp: Pausing playback Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO SampleApp: Playback state changed: Paused Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming ended event Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:30 volumio qobuz-connect[2581]: 20261011 23:31:30.991 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:30 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:30 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:30 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:30 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:30 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:30 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:30 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:30 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:30 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:30 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:30 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:31 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:31 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:31 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:31 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:31 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754176791 Oct 11 23:31:31 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:31 volumio volumio[1337]: -------------------------------------------------// P A U S I N G (remaining: 155290 milliseconds) Oct 11 23:31:31 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:31 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:31 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754176791 Oct 11 23:31:31 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:31 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:31 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:31 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754176791 Oct 11 23:31:31 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:35 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:35.164+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.62:38864 Oct 11 23:31:35 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.244:3000 from 192.168.1.62 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:31:35 volumio volumio[1337]: info: Discovery: Getting this device information Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:31:35 volumio volumio[1337]: info: Discovery: Getting this device information Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:31:35 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.244:3000 from 192.168.1.62 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 23:31:35 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.182+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.443368ms platform=PLATFORM_ANDROID version=6.260915.0 Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.186+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.431285ms timeout=20s Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.186+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" Oct 11 23:31:36 volumio volumio[1337]: info: Received Get System Info Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:31:36 volumio volumio[1337]: info: Discovery: Getting this device information Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.187+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" name=Volumio Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.188+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" language=it Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.189+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" timezone=Europe/Rome Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.189+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" available=true connected=true macAddress=3c:07:54:53:dc:9b ip4Address=192.168.1.244/24 ip6Address= Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.190+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.190+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" setupComplete=true Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 23:31:36 volumio volumio[1337]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 1 Oct 11 23:31:36 volumio volumio[1337]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 11 23:31:36 volumio volumio[1337]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 1\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 11 23:31:36 volumio volumio[1337]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 0 Oct 11 23:31:36 volumio volumio[1337]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 11 23:31:36 volumio volumio[1337]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 11 23:31:36 volumio volumio[1337]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 3 Oct 11 23:31:36 volumio volumio[1337]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 11 23:31:36 volumio volumio[1337]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 11 23:31:36 volumio volumio[1337]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 11 23:31:36 volumio volumio[1337]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 11 23:31:36 volumio volumio[1337]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 11 23:31:36 volumio volumio[1337]: amixer -c 5 info | grep "LAIV Harmony uDAC" Oct 11 23:31:36 volumio volumio[1337]: Card sysdefault:5 'uDAC'/'LAIV Audio LAIV Harmony uDAC at usb-0000:00:1d.7-1.3, high speed' Oct 11 23:31:36 volumio volumio[1337]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 11 23:31:36 volumio volumio[1337]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 11 23:31:36 volumio volumio[1337]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 11 23:31:36 volumio volumio[1337]: amixer -c 5 info | grep "LAIV Harmony uDAC" Oct 11 23:31:36 volumio volumio[1337]: Card sysdefault:5 'uDAC'/'LAIV Audio LAIV Harmony uDAC at usb-0000:00:1d.7-1.3, high speed' Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.229+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" selectedOutputId=5 Oct 11 23:31:36 volumio volumio[1337]: info: Received Get System Info Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:31:36 volumio volumio[1337]: info: Discovery: Getting this device information Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.247+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" currentVersion=4.119 latestVersion=4.119 Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.247+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" status=UPDATE_STATUS_NONE progress=0 Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.247+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" userId= Oct 11 23:31:36 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:36.247+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" providers=3 Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 23:31:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.737+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" plugins=57 Oct 11 23:31:37 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=326189 volume=100 Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.738+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.763+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.485523ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.787+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.925+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=http://pushupdates.volumio.org duration=136.484369ms Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.929+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=141.942403ms Oct 11 23:31:37 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:37.997+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=208.435346ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.015+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=226.342417ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.027+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://www.googleapis.com duration=239.646681ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.061+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://securetoken.googleapis.com duration=273.472281ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.143+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=354.971671ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.199+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://functions.volumio.cloud duration=410.407165ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.203+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://functions.volumio.cloud duration=414.456939ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.380+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=http://cddb.volumio.org duration=592.576964ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.613+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://database.volumio.cloud duration=825.381547ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.684+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=https://google.com duration=896.270068ms Oct 11 23:31:38 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:38.952+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.62:38864 @ 0xc0002ff950" latency=-7.372947ms timeout=10s endpoint=http://plugins.volumio.org duration=1.164488263s Oct 11 23:31:39 volumio sudo[20292]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 23:31:39 volumio sudo[20292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 23:31:39 volumio sudo[20294]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 23:31:39 volumio sudo[20294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 23:31:39 volumio sudo[20292]: pam_unix(sudo:session): session closed for user root Oct 11 23:31:39 volumio sudo[20294]: pam_unix(sudo:session): session closed for user root Oct 11 23:31:39 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.62 UA: Mozilla/5.0 (Linux; Android 13; 2201117TY Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.57 Mobile Safari/537.36 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 11 23:31:39 volumio sudo[20299]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 23:31:39 volumio sudo[20299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 23:31:39 volumio sudo[20299]: pam_unix(sudo:session): session closed for user root Oct 11 23:31:39 volumio sudo[20301]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 23:31:39 volumio sudo[20301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 23:31:39 volumio sudo[20301]: pam_unix(sudo:session): session closed for user root Oct 11 23:31:39 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.62 UA: Mozilla/5.0 (Linux; Android 13; 2201117TY Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.57 Mobile Safari/537.36 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 11 Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 23:31:40 volumio volumio[1337]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 11 23:31:40 volumio volumio[1337]: info: Received Get System Info Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:31:40 volumio volumio[1337]: info: Discovery: Getting this device information Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:40 volumio volumio[1337]: info: Listing playlists Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 23:31:40 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 23:31:41 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 23:31:41 volumio volumio[1337]: info: Received Get System Info Oct 11 23:31:41 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:31:41 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:31:41 volumio volumio[1337]: info: Discovery: Getting this device information Oct 11 23:31:41 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:41 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:31:41 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 23:31:43 volumio volumio[1337]: info: Received Get System Info Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:31:43 volumio volumio[1337]: info: Discovery: Getting this device information Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioSeek Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::seek Oct 11 23:31:43 volumio volumio[1337]: info: QobuzConnect: Received seek, position: 153000 Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.452 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending RendererAction message Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.452 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Oct 11 23:31:43 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.454+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=326189 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.454+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:43 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:43 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754176791 Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Received SetState message: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playing state: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playback position: 153000 Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Queue version: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Current track: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Next track: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Seeking to time offset: 153000ms Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Seek is in progress Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 17]: Seeking to time offset: 153000ms Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO UrlAudioSource: [0x55c0aa390a40]: Seeking URL audio source to time offset: 153000ms Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO SampleApp: Seek is in progress Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO UrlAudioSource: [0x55c0aa390a40]: Seeking input stream to byte offset: 44975566 Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.503 [2581.2581] INFO ContentFetcher: [0x55c0aa8f5d30]: Adjusting download offset to: 44975566 Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.507 [2581.2581] INFO SampleApp: Playback state changed: Seeking Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.507 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.507 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.507 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.509+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=326189 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.509+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:43 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:43 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754176791 Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] Continuing scrobbling of paused song, starting new timer for the remainder of 155290 milliseconds [Santtu-Matias Rouvali - Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela]. Oct 11 23:31:43 volumio volumio[1337]: -------------------------------------------------// S T O P P I N G Oct 11 23:31:43 volumio volumio[1337]: -------------------------------------------------// S T A R T I N G Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] Timer started with time stamp 1791754303 Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.699 [2581.2581] INFO UrlAudioSource: [0x55c0aa390a40]: Seeking input stream to byte offset: 45027398 Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.699 [2581.2581] INFO ContentFetcher: [0x55c0aa8f5d30]: Adjusting download offset to: 45027398 Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.779 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Current stream has been seeked Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.779 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Seek has finished Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.779 [2581.2581] INFO SampleApp: [Stream 17]: Audio stream seeked: 153000ms Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.779 [2581.2581] INFO SampleApp: Playback state changed: Paused Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.779 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.779 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.779 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.782+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=326189 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.782+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.782+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=153000 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.782+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:43 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:43 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754303517 Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:43 volumio volumio[1337]: -------------------------------------------------// P A U S I N G (remaining: 155017 milliseconds) Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:43 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:43 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754303517 Oct 11 23:31:43 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPrevious Oct 11 23:31:43 volumio volumio[1337]: info: QobuzConnect: Received previous Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.888 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending RendererAction message Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.888 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Received SetState message: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playing state: Playing Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playback position: 0 Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Queue version: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Current track: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Next track: Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Resuming playback Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Seeking to time offset: 0ms Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Resuming audio renderer Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO SampleApp: Resuming playback Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO SampleApp: Playback state changed: Playing Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming started event Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Seek is in progress Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 17]: Seeking to time offset: 0ms Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO UrlAudioSource: [0x55c0aa390a40]: Seeking URL audio source to time offset: 0ms Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO UrlAudioSource: [0x55c0aa390a40]: Seeking input stream to byte offset: 1018 Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.980 [2581.2581] INFO ContentFetcher: [0x55c0aa8f5d30]: Adjusting download offset to: 1018 Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.982 [2581.2581] INFO SampleApp: Seek is in progress Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.982 [2581.2581] INFO SampleApp: Playback state changed: Seeking Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.982 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming ended event Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.982 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.982 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:43 volumio qobuz-connect[2581]: 20261011 23:31:43.982 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.984+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=153000 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.985+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=153000 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.984+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=153000 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.985+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.985+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.985+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:43 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:43 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.990+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=153000 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.990+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.990+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=153000 volume=100 Oct 11 23:31:43 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:43.990+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:43 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754303517 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754303517 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Continuing scrobbling of paused song, starting new timer for the remainder of 155017 milliseconds [Santtu-Matias Rouvali - Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela]. Oct 11 23:31:44 volumio volumio[1337]: -------------------------------------------------// S T O P P I N G Oct 11 23:31:44 volumio volumio[1337]: -------------------------------------------------// S T A R T I N G Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Timer started with time stamp 1791754304 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.075 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Current stream has been seeked Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Seek has finished Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO SampleApp: [Stream 17]: Audio stream seeked: 0ms Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO SampleApp: Playback state changed: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming started event Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.100 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.103+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.103+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.108+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.108+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.109+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.109+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.109+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.109+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.300 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPrevious Oct 11 23:31:44 volumio volumio[1337]: info: QobuzConnect: Received previous Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.321 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending RendererAction message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.321 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Received SetState message: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playing state: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playback position: 0 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Queue version: 75.2 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Current track: TID: 401237759, QID: 16, Context UUID: 899146ae-0605-4c2c-b847-b9b783e1ee1f Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Next track: TID: 401237760, QID: 17, Context UUID: 899146ae-0605-4c2c-b847-b9b783e1ee1f Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Stopping playback, clearing tracks Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Initiating playback Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Stopping audio renderer Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.378 [2581.2581] INFO SampleApp: Stopping playback Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.391 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Clearing all streams Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.391 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 17]: Disposing of audio stream Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.391 [2581.2581] INFO ContentFetcher: [0x55c0aa8f5d30]: Canceling download Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO SampleApp: Playback state changed: Stopped Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming ended event Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO SampleApp: [Stream 17]: Audio stream has been disposed of Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Setting current track: 401237759, initial offset: 0ms Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Clearing all streams Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: New stream: 19 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO HttpDownloader: [0x55c0aa63dcd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=63c7e6360487bcebd1a6c277c97782a3&request_ts=1791754304&track_id=401237759 Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO HttpDownloader: [0x55c0aa740180]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401237759 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO HttpDownloader: [0x55c0aa9f91d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6832c85f61f3a291a686c62b7921ea8d&request_ts=1791754304&track_id=401237759 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 19]: Running audio stream Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Setting next track: 401237760 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: New stream: 20 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO HttpDownloader: [0x55c0aa4f2330]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=573d2deaf26cfb8c4b32820632aeed45&request_ts=1791754304&track_id=401237760 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO HttpDownloader: [0x55c0aa5162f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401237760 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO HttpDownloader: [0x55c0aa515e20]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=801527e1147a710d0bcba2a3603e8459&request_ts=1791754304&track_id=401237760 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.394 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Waiting for current stream to start before starting audio renderer Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.398+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.398+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.398+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.398+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela" Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:44 volumio volumio[1337]: -------------------------------------------------// P A U S I N G (remaining: 154628 milliseconds) Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 539 | title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.501 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 19]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237759&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=G76o4XRwHh6YWFaj2d_OrqIsh8o Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.523 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 20]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237760&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=hTFTW4j1AyuQl2jqRhkDS6xZE8E Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.534 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 19]: Metadata became available: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.534 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.534 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Artist: Santtu-Matias Rouvali Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.534 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album: Sibelius: Violin Concerto - Lemminkäinen Suite Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.534 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album art URL: https://static.qobuz.com/images/covers/nt/n1/eipd34vton1nt_600.jpg Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 19]: stream information have been fetched Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO UrlAudioSource: [0x55c0aaa5ab40]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237759&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=G76o4XRwHh6YWFaj2d_OrqIsh8o Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO ContentFetcher: [0x55c0aa9fb570]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237759&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=G76o4XRwHh6YWFaj2d_OrqIsh8o, offset: 0 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Starting audio renderer, initial playback state: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO SampleApp: [Stream 19]: New audio stream (starting from 0ms) Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO SampleApp: [Stream 19]: Stream metadata became available: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO SampleApp: Title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO SampleApp: Artist: Santtu-Matias Rouvali Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO SampleApp: Album: Sibelius: Violin Concerto - Lemminkäinen Suite Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.574 [2581.2581] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/nt/n1/eipd34vton1nt_600.jpg Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: QobuzConnect: alread currrent service, send playback confirmation Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.575 [2581.2581] INFO SampleApp: Initiating playback, initial state: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.578 [2581.2581] INFO SampleApp: Playback state changed: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.578 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming started event Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.578 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.578 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.578 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island" Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island" Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island" Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.585+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island" Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.586 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 20]: stream information have been fetched Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.593 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 20]: Metadata became available: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.593 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.593 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Artist: Santtu-Matias Rouvali Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.593 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album: Sibelius: Violin Concerto - Lemminkäinen Suite Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.593 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album art URL: https://static.qobuz.com/images/covers/nt/n1/eipd34vton1nt_600.jpg Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 962 | title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 962 | title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island | previous title: Lemminkäinen Suite, Op. 22: II. The Swan of Tuonela Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754304011 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Current track has sufficient metadata: title (Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island) and artist (Santtu-Matias Rouvali) passed on explicitly Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Updating now playing Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] starting new timer for 481000 milliseconds [Santtu-Matias Rouvali - Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island]. Oct 11 23:31:44 volumio volumio[1337]: -------------------------------------------------// S T O P P I N G Oct 11 23:31:44 volumio volumio[1337]: -------------------------------------------------// S T A R T I N G Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Timer started with time stamp 1791754304 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 962 | title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island | previous title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304605 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 962 | title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island | previous title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304605 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Same state as the one previously pushed. No need to do anything... Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPrevious Oct 11 23:31:44 volumio volumio[1337]: info: QobuzConnect: Received previous Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.613 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending RendererAction message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.613 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- wakeupScreen: DPMS - screen on Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.651 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.651 [2581.2581] INFO UrlAudioSource: [0x55c0aaa5ab40]: Content info extracted Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.651 [2581.2581] INFO UrlAudioSource: [0x55c0aaa5ab40]: Size: 299063527 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.652 [2581.20348] INFO AudioDecoder: [0x55c0aa7aab80]: Stream info: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.652 [2581.20348] INFO AudioDecoder: [0x55c0aa7aab80]: Total samples: 92336000 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.652 [2581.20348] INFO AudioDecoder: [0x55c0aa7aab80]: Sample rate: 96000 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.652 [2581.20348] INFO AudioDecoder: [0x55c0aa7aab80]: Channel count: 2 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.652 [2581.20348] INFO AudioDecoder: [0x55c0aa7aab80]: Bits per sample: 24 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.652 [2581.20348] INFO AudioDecoder: [0x55c0aa7aab80]: Duration: 961833 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.652 [2581.2581] INFO UrlAudioSource: [0x55c0aaa5ab40]: Audio properties have changed Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Received SetState message: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playing state: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Playback position: 0 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Queue version: 75.2 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Current track: TID: 401237758, QID: 15, Context UUID: 899146ae-0605-4c2c-b847-b9b783e1ee1f Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Next track: TID: 401237759, QID: 16, Context UUID: 899146ae-0605-4c2c-b847-b9b783e1ee1f Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Stopping playback, clearing tracks Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Initiating playback Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Stopping audio renderer Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.670 [2581.2581] INFO SampleApp: Stopping playback Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.671 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Clearing all streams Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.671 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 19]: Disposing of audio stream Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.671 [2581.2581] INFO ContentFetcher: [0x55c0aa9fb570]: Canceling download Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO SampleApp: Playback state changed: Stopped Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming ended event Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO SampleApp: [Stream 19]: Audio stream has been disposed of Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Setting current track: 401237758, initial offset: 0ms Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Clearing all streams Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.672 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: New stream: 21 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO HttpDownloader: [0x55c0aa4298c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=058a5fb616cfab377c6afd901c95e143&request_ts=1791754304&track_id=401237758 Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO HttpDownloader: [0x55c0aa50fc60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401237758 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO HttpDownloader: [0x55c0aa502510]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a2c741a1f9e338a9753a862666111eda&request_ts=1791754304&track_id=401237758 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 21]: Running audio stream Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Setting next track: 401237759 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: New stream: 22 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO HttpDownloader: [0x55c0aa8976d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=63c7e6360487bcebd1a6c277c97782a3&request_ts=1791754304&track_id=401237759 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO HttpDownloader: [0x55c0aa38d660]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=401237759 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO HttpDownloader: [0x55c0aa98dd70]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6832c85f61f3a291a686c62b7921ea8d&request_ts=1791754304&track_id=401237759 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.673 [2581.2581] INFO MediaEngine: [0x55c0aa388550]: Waiting for current stream to start before starting audio renderer Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.675+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.675+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.676+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island" Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.676+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island" Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 962 | title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island | previous title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: true | can continue: false | timer started at: 1791754304605 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:44 volumio volumio[1337]: -------------------------------------------------// P A U S I N G (remaining: 480927 milliseconds) Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 962 | title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island | previous title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754304605 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.746 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 22]: Metadata became available: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.746 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.746 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Artist: Santtu-Matias Rouvali Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.746 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album: Sibelius: Violin Concerto - Lemminkäinen Suite Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.746 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album art URL: https://static.qobuz.com/images/covers/nt/n1/eipd34vton1nt_600.jpg Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.747 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.815 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 21]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237758&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=fnb6SbRpitFV6DwP4-92XYXIjHg Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.832 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 22]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237759&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=G76o4XRwHh6YWFaj2d_OrqIsh8o Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.848 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 21]: stream information have been fetched Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.848 [2581.2581] INFO UrlAudioSource: [0x55c0aa61a9d0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237758&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=fnb6SbRpitFV6DwP4-92XYXIjHg Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.848 [2581.2581] INFO ContentFetcher: [0x55c0aa9fb570]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=401237758&fmt=7&profile=raw&app_id=174516466&cid=3860144&etsp=1791757904&hmac=fnb6SbRpitFV6DwP4-92XYXIjHg, offset: 0 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.848 [2581.2581] INFO AudioRenderer: [0x55c0aa3887d0]: Starting audio renderer, initial playback state: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.848 [2581.2581] INFO SampleApp: [Stream 21]: New audio stream (starting from 0ms) Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: QobuzConnect: alread currrent service, send playback confirmation Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.849 [2581.2581] INFO SampleApp: Initiating playback, initial state: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.852 [2581.2581] INFO SampleApp: Playback state changed: Playing Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.852 [2581.2581] INFO StreamingEventReporter: [0x55c0aa389690]: Reporting streaming started event Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.852 [2581.2581] INFO ProtocolHandler: [0x55c0aa388fc0]: Need to send state update Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.852 [2581.2581] INFO QConnectMessageSender: [0x55c0aa516c00]: Sending StateUpdated message Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.852 [2581.2581] INFO QwspMessageSender: [0x55c0aa51a510]: Sending Payload message Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 21]: Metadata became available: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Title: Violin Concerto in D Minor, Op. 47: III. Allegro, ma non tanto Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Artist: Ava Bahari Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album: Sibelius: Violin Concerto - Lemminkäinen Suite Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: Album art URL: https://static.qobuz.com/images/covers/nt/n1/eipd34vton1nt_600.jpg Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO SampleApp: [Stream 21]: Stream metadata became available: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO SampleApp: Title: Violin Concerto in D Minor, Op. 47: III. Allegro, ma non tanto Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO SampleApp: Artist: Ava Bahari Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO SampleApp: Album: Sibelius: Violin Concerto - Lemminkäinen Suite Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.853 [2581.2581] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/nt/n1/eipd34vton1nt_600.jpg Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.857+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.857+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.857+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title= Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.857+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title= Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.857+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.858+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title= Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::servicePushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreStateMachine::pushState Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioPushState Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.861 [2581.2581] INFO AudioStreamManager: [0x55c0aa388680]: [Stream 22]: stream information have been fetched Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output update for this device Oct 11 23:31:44 volumio volumio[1337]: info: MRS: Pushing multiroomSync output Oct 11 23:31:44 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.864+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.864+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title= Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=pause timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.866+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 23:31:44 volumio volumio5-onboarding[2022]: time=2026-10-11T23:31:44.866+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38864 @ 0xc0002ff950" id= title="Violin Concerto in D Minor, Op. 47: III. Allegro, ma non tanto" Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- Volumio status=play timeout=300 noifplay=true screensavertype=dpms Oct 11 23:31:44 volumio volumio[1337]: info: Display-configuration --- → Wakeup triggered Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: pause | service: qobuzconnect | duration: 465 | title: | previous title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754304605 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Song has been paused, so also pausing timer. Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 465 | title: | previous title: Lemminkäinen Suite, Op. 22: I. Lemminkäinen and the Maidens of the Island Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754304605 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] using existing timer Oct 11 23:31:44 volumio volumio[1337]: info: --------------------------------------------------------------------// [LastFM] new state has been pushed; status: play | service: qobuzconnect | duration: 465 | title: undefined | previous title: Oct 11 23:31:44 volumio volumio[1337]: info: =================> [timer] is active: false | can continue: true | timer started at: 1791754304605 Oct 11 23:31:44 volumio volumio[1337]: info: [LastFM] Playback detected, evaluating parameters for scrobbling... Oct 11 23:31:44 volumio volumio[1337]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:31:44 volumio volumio[1337]: TypeError: Cannot read properties of undefined (reading 'indexOf') Oct 11 23:31:44 volumio volumio[1337]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Oct 11 23:31:44 volumio volumio[1337]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Oct 11 23:31:44 volumio volumio[1337]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Oct 11 23:31:44 volumio volumio[1337]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 11 23:31:44 volumio volumio[1337]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Oct 11 23:31:44 volumio volumio[1337]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Oct 11 23:31:44 volumio volumio[1337]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 11 23:31:44 volumio volumio[1337]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 11 23:31:44 volumio volumio[1337]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Oct 11 23:31:44 volumio volumio[1337]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 11 23:31:44 volumio volumio[1337]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Oct 11 23:31:44 volumio volumio[1337]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Oct 11 23:31:44 volumio volumio[1337]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Oct 11 23:31:44 volumio volumio[1337]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 11 23:31:44 volumio volumio[1337]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 11 23:31:44 volumio volumio[1337]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Oct 11 23:31:44 volumio volumio[1337]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.927 [2581.2581] INFO HttpDownloader: [0x55c0aa389720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.953 [2581.2581] INFO UrlAudioSource: [0x55c0aa61a9d0]: Content info extracted Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.953 [2581.2581] INFO UrlAudioSource: [0x55c0aa61a9d0]: Size: 148771420 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.954 [2581.20368] INFO AudioDecoder: [0x55c0aa494180]: Stream info: Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.954 [2581.20368] INFO AudioDecoder: [0x55c0aa494180]: Total samples: 44572000 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.954 [2581.20368] INFO AudioDecoder: [0x55c0aa494180]: Sample rate: 96000 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.954 [2581.20368] INFO AudioDecoder: [0x55c0aa494180]: Channel count: 2 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.954 [2581.20368] INFO AudioDecoder: [0x55c0aa494180]: Bits per sample: 24 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.954 [2581.20368] INFO AudioDecoder: [0x55c0aa494180]: Duration: 464291 Oct 11 23:31:44 volumio qobuz-connect[2581]: 20261011 23:31:44.955 [2581.2581] INFO UrlAudioSource: [0x55c0aa61a9d0]: Audio properties have changed Oct 11 23:31:44 volumio sudo[20371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 23:30' Oct 11 23:31:44 volumio sudo[20371]: 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"