Aug 31 23:11:08 volumio qobuz-connect[2516]: 20260831 23:11:08.412 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:11:08 volumio qobuz-connect[2516]: 20260831 23:11:08.412 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:11:08 volumio qobuz-connect[2516]: 20260831 23:11:08.412 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:11:08 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:08 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:11:08 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:11:08 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:11:08 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:11:08 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:11:08 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:11:08 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:08.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=70000 volume=100 Aug 31 23:11:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:08.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=70000 volume=100 Aug 31 23:11:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:08.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:11:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:08.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:11:08 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:08 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:08 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:08 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:08 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:08 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:18 volumio qobuz-connect[2516]: 20260831 23:11:18.412 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:11:18 volumio qobuz-connect[2516]: 20260831 23:11:18.412 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:11:18 volumio qobuz-connect[2516]: 20260831 23:11:18.412 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:11:18 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:18 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:11:18 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:11:18 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:11:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:11:18 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:11:18 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:11:18 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:18.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=79999 volume=100 Aug 31 23:11:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:18.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=79999 volume=100 Aug 31 23:11:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:18.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:11:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:18.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:11:18 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:18 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:18 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:18 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:18 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:18 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:28 volumio qobuz-connect[2516]: 20260831 23:11:28.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:11:28 volumio qobuz-connect[2516]: 20260831 23:11:28.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:11:28 volumio qobuz-connect[2516]: 20260831 23:11:28.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:11:28 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:28 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:11:28 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:11:28 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:11:28 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:11:28 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:11:28 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:11:28 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:28.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=89998 volume=100 Aug 31 23:11:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:28.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=89998 volume=100 Aug 31 23:11:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:28.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:11:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:28.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:11:28 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:28 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:28 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:28 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:28 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:28 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:38 volumio qobuz-connect[2516]: 20260831 23:11:38.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:11:38 volumio qobuz-connect[2516]: 20260831 23:11:38.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:11:38 volumio qobuz-connect[2516]: 20260831 23:11:38.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:11:38 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:38 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:11:38 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:11:38 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:11:38 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:11:38 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:11:38 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:11:38 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:38.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=99999 volume=100 Aug 31 23:11:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:38.415+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=99999 volume=100 Aug 31 23:11:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:38.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:11:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:38.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:11:38 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:38 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:38 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:38 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:38 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:38 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:48 volumio qobuz-connect[2516]: 20260831 23:11:48.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:11:48 volumio qobuz-connect[2516]: 20260831 23:11:48.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:11:48 volumio qobuz-connect[2516]: 20260831 23:11:48.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:11:48 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:48 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:11:48 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:11:48 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:11:48 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:11:48 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:11:48 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:11:48 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:48 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:48.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=109999 volume=100 Aug 31 23:11:48 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:48.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=109999 volume=100 Aug 31 23:11:48 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:48.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:11:48 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:48.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:11:48 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:48 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:48 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:48 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:48 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:48 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:58 volumio qobuz-connect[2516]: 20260831 23:11:58.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:11:58 volumio qobuz-connect[2516]: 20260831 23:11:58.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:11:58 volumio qobuz-connect[2516]: 20260831 23:11:58.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:11:58 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:58 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:11:58 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:11:58 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:11:58 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:11:58 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:11:58 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:11:58 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:11:58 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:58.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=119999 volume=100 Aug 31 23:11:58 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:58.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=119999 volume=100 Aug 31 23:11:58 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:58.413+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:11:58 volumio volumio5-onboarding[1923]: time=2026-08-31T23:11:58.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:11:58 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:58 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:58 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:11:58 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:11:58 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:11:58 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:08 volumio qobuz-connect[2516]: 20260831 23:12:08.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:12:08 volumio qobuz-connect[2516]: 20260831 23:12:08.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:12:08 volumio qobuz-connect[2516]: 20260831 23:12:08.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:08 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:08 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:08 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:08 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:08 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:08 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:08 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:08 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:08.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=129998 volume=100 Aug 31 23:12:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:08.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=129998 volume=100 Aug 31 23:12:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:08.413+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:12:08 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:08.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:12:08 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:08 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:08 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:08 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:08 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:08 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:18 volumio qobuz-connect[2516]: 20260831 23:12:18.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:12:18 volumio qobuz-connect[2516]: 20260831 23:12:18.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:12:18 volumio qobuz-connect[2516]: 20260831 23:12:18.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:18 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:18 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:18 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:18 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:18 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:18 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:18 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:18.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=139997 volume=100 Aug 31 23:12:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:18.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=139997 volume=100 Aug 31 23:12:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:18.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:12:18 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:18.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:12:18 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:18 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:18 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:18 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:18 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:18 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:28 volumio qobuz-connect[2516]: 20260831 23:12:28.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:12:28 volumio qobuz-connect[2516]: 20260831 23:12:28.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:12:28 volumio qobuz-connect[2516]: 20260831 23:12:28.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:28 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:28 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:28 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:28 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:28 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:28 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:28 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:28 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:28.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=149998 volume=100 Aug 31 23:12:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:28.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=149998 volume=100 Aug 31 23:12:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:28.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:12:28 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:28.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:12:28 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:28 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:28 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:28 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:28 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:28 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:38 volumio qobuz-connect[2516]: 20260831 23:12:38.411 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:12:38 volumio qobuz-connect[2516]: 20260831 23:12:38.411 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:12:38 volumio qobuz-connect[2516]: 20260831 23:12:38.411 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:38 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:38 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:38 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:38 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:38 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:38 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:38 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:38 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:38.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=159999 volume=100 Aug 31 23:12:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:38.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=159999 volume=100 Aug 31 23:12:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:38.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:12:38 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:38.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:12:38 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:38 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:38 volumio volumio[1326]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:38 volumio volumio[1326]: info: Display-configuration --- → Wakeup triggered Aug 31 23:12:38 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:38 volumio volumio[1326]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.116 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Received SetState message: Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.116 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Playing state: Playing Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.116 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Playback position: 0 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.116 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Queue version: 177.1 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.117 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Current track: TID: 198131576, QID: 0, Context UUID: 6adff001-1e2d-4c8d-9afe-fbb0dc756034 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.117 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Next track: TID: 198131577, QID: 1, Context UUID: 6adff001-1e2d-4c8d-9afe-fbb0dc756034 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.117 [2516.2516] INFO MediaEngine: [0x55ecc05d8550]: Stopping playback, clearing tracks Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.117 [2516.2516] INFO MediaEngine: [0x55ecc05d8550]: Initiating playback Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.117 [2516.2516] INFO AudioRenderer: [0x55ecc05d87d0]: Stopping audio renderer Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.117 [2516.2516] INFO SampleApp: Stopping playback Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.136 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: Clearing all streams Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.136 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: [Stream 47]: Disposing of audio stream Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.136 [2516.2516] INFO ContentFetcher: [0x55ecc0670850]: Canceling download Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.138 [2516.2516] INFO SampleApp: Playback state changed: Stopped Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.138 [2516.2516] INFO StreamingEventReporter: [0x55ecc05d9690]: Reporting streaming ended event Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.138 [2516.2516] INFO HttpDownloader: [0x55ecc05d9720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.138 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.138 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.138 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.138 [2516.2516] INFO SampleApp: [Stream 47]: Audio stream has been disposed of Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.140+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PAUSED positionMs=159999 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.140+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PAUSED positionMs=159999 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.140+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.140+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO MediaEngine: [0x55ecc05d8550]: Setting current track: 198131576, initial offset: 0ms Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: Clearing all streams Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: New stream: 49 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO HttpDownloader: [0x55ecc09da8d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b0ba49a5d6431f3d19f381a702e41813&request_ts=1788210761&track_id=198131576 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO HttpDownloader: [0x55ecc06f7e40]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=198131576 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO HttpDownloader: [0x55ecc08b4360]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6c92314747f8053186e0be47f399db46&request_ts=1788210761&track_id=198131576 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: [Stream 49]: Running audio stream Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO MediaEngine: [0x55ecc05d8550]: Setting next track: 198131577 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: New stream: 50 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO HttpDownloader: [0x55ecc09da520]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9a041cc233bbf44ddc5fa854fad468af&request_ts=1788210761&track_id=198131577 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO HttpDownloader: [0x55ecc07a7440]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=198131577 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO HttpDownloader: [0x55ecc0b84060]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b61ca6d088769c1b53b29f2f3f6822f1&request_ts=1788210761&track_id=198131577 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.143 [2516.2516] INFO MediaEngine: [0x55ecc05d8550]: Waiting for current stream to start before starting audio renderer Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.146+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.146+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PAUSED positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.146+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title=Zagara Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.146+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title=Zagara Aug 31 23:12:41 volumio volumio[1326]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:41 volumio volumio[1326]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:41 volumio volumio[1326]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:41 volumio volumio[1326]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.515 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: [Stream 50]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=198131577&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1788214361&hmac=kPfpWVPRLrzku4HbWXWslEQII_Y Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.522 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: [Stream 49]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=198131576&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1788214361&hmac=Kxq_PVcvamIIEx4dEHDhbtu-QsQ Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.539 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: [Stream 49]: stream information have been fetched Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.539 [2516.2516] INFO UrlAudioSource: [0x55ecc05e0a40]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=198131576&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1788214361&hmac=Kxq_PVcvamIIEx4dEHDhbtu-QsQ Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.540 [2516.2516] INFO ContentFetcher: [0x55ecc06ecf50]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10433069&eid=198131576&fmt=6&profile=raw&app_id=174516466&cid=3860144&etsp=1788214361&hmac=Kxq_PVcvamIIEx4dEHDhbtu-QsQ, offset: 0 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.540 [2516.2516] INFO AudioRenderer: [0x55ecc05d87d0]: Starting audio renderer, initial playback state: Playing Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.540 [2516.2516] INFO SampleApp: [Stream 49]: New audio stream (starting from 0ms) Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: QobuzConnect: alread currrent service, send playback confirmation Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.540 [2516.2516] INFO SampleApp: Initiating playback, initial state: Playing Aug 31 23:12:41 volumio qobuz-connect[2516]: ALSA lib ./src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Aug 31 23:12:41 volumio qobuz-connect[2516]: ALSA lib ./src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.542 [2516.2516] INFO SampleApp: Playback state changed: Playing Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.542 [2516.2516] INFO StreamingEventReporter: [0x55ecc05d9690]: Reporting streaming started event Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO HttpDownloader: [0x55ecc05d9720]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO ProtocolHandler: [0x55ecc05d8fc0]: Need to send state update Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending StateUpdated message Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending FileAudioQualityChanged message Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO QConnectMessageSender: [0x55ecc076c960]: Sending DeviceAudioQualityChanged message Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.543 [2516.2516] INFO QwspMessageSender: [0x55ecc0709c40]: Sending Payload message Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PAUSED positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title= Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title= Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title= Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title= Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.547+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title= Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.548+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title= Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreStateMachine::pushState Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output update for this device Aug 31 23:12:41 volumio volumio[1326]: info: MRS: Pushing multiroomSync output Aug 31 23:12:41 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.551+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.551+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.552+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.62:38436 @ 0xc000479dd0" id= title= Aug 31 23:12:41 volumio volumio5-onboarding[1923]: time=2026-08-31T23:12:41.552+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:40024 @ 0xc0005ad230" id= title= Aug 31 23:12:41 volumio volumio[1326]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Aug 31 23:12:41 volumio volumio[1326]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:12:41 volumio volumio[1326]: TypeError: Cannot read properties of undefined (reading 'indexOf') Aug 31 23:12:41 volumio volumio[1326]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Aug 31 23:12:41 volumio volumio[1326]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Aug 31 23:12:41 volumio volumio[1326]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Aug 31 23:12:41 volumio volumio[1326]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Aug 31 23:12:41 volumio volumio[1326]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Aug 31 23:12:41 volumio volumio[1326]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Aug 31 23:12:41 volumio volumio[1326]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Aug 31 23:12:41 volumio volumio[1326]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Aug 31 23:12:41 volumio volumio[1326]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Aug 31 23:12:41 volumio volumio[1326]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Aug 31 23:12:41 volumio volumio[1326]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Aug 31 23:12:41 volumio volumio[1326]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Aug 31 23:12:41 volumio volumio[1326]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Aug 31 23:12:41 volumio volumio[1326]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Aug 31 23:12:41 volumio volumio[1326]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Aug 31 23:12:41 volumio volumio[1326]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Aug 31 23:12:41 volumio volumio[1326]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.569 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: [Stream 50]: Metadata became available: Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.570 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: Title: angel Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.570 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: Artist: Bluem Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.570 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: Album: nou Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.570 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: Album art URL: https://static.qobuz.com/images/covers/ha/8q/apbcoo7qk8qha_600.jpg Aug 31 23:12:41 volumio qobuz-connect[2516]: 20260831 23:12:41.599 [2516.2516] INFO AudioStreamManager: [0x55ecc05d8680]: [Stream 50]: stream information have been fetched Aug 31 23:12:41 volumio sudo[72339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 23:11' Aug 31 23:12:41 volumio sudo[72339]: 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"