Aug 27 00:34:05 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:34:06 rene-pi qobuz-connect[2070]: 20260827 00:34:06.885 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:06 rene-pi qobuz-connect[2070]: 20260827 00:34:06.885 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:06 rene-pi qobuz-connect[2070]: 20260827 00:34:06.885 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:06 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:06 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:06 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:06 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:06 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:06 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:06 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:06 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:06 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:06.893+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=200471 volume=100 Aug 27 00:34:06 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:06.893+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title="Hazel Eyes" Aug 27 00:34:13 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:34:16 rene-pi qobuz-connect[2070]: 20260827 00:34:16.884 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:16 rene-pi qobuz-connect[2070]: 20260827 00:34:16.885 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:16 rene-pi qobuz-connect[2070]: 20260827 00:34:16.885 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:16 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:16 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:16 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:16 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:16 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:16 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:16 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:16 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:16 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:16.891+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=210469 volume=100 Aug 27 00:34:16 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:16.892+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title="Hazel Eyes" Aug 27 00:34:21 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:34:26 rene-pi qobuz-connect[2070]: 20260827 00:34:26.885 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:26 rene-pi qobuz-connect[2070]: 20260827 00:34:26.885 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:26 rene-pi qobuz-connect[2070]: 20260827 00:34:26.885 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:26 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:26 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:26 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:26 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:26 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:26 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:26 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:26 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:26 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:26.894+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=220471 volume=100 Aug 27 00:34:26 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:26.895+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title="Hazel Eyes" Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: [Stream 2]: Running audio stream Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO UrlAudioSource: [0xc061e8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2150301&eid=445382939&fmt=7&profile=raw&app_id=174516466&cid=1512846&etsp=1787787054&hmac=c8UOb8nodYhqBbzYlCApqZCUnmA Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO ContentFetcher: [0xc50040]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2150301&eid=445382939&fmt=7&profile=raw&app_id=174516466&cid=1512846&etsp=1787787054&hmac=c8UOb8nodYhqBbzYlCApqZCUnmA, offset: 0 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO SampleApp: [Stream 2]: New audio stream (starting from 0ms) Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO SampleApp: [Stream 2]: Stream metadata became available: Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO SampleApp: Title: Moondance Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO SampleApp: Artist: Sam Smith Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO SampleApp: Album: Hazel Eyes Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.533 [2070.2070] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ve/dj/vafgxaiq1djve_600.jpg Aug 27 00:34:29 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:29 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.2070] INFO UrlAudioSource: [0xc061e8]: Content info extracted Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.2070] INFO UrlAudioSource: [0xc061e8]: Size: 96098674 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.7516] INFO AudioDecoder: [0xba38d0]: Stream info: Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.7516] INFO AudioDecoder: [0xba38d0]: Total samples: 25313006 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.7516] INFO AudioDecoder: [0xba38d0]: Sample rate: 96000 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.7516] INFO AudioDecoder: [0xba38d0]: Channel count: 2 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.7516] INFO AudioDecoder: [0xba38d0]: Bits per sample: 24 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.7516] INFO AudioDecoder: [0xba38d0]: Duration: 263677 Aug 27 00:34:29 rene-pi qobuz-connect[2070]: 20260827 00:34:29.690 [2070.2070] INFO UrlAudioSource: [0xc061e8]: Audio properties have changed Aug 27 00:34:36 rene-pi qobuz-connect[2070]: 20260827 00:34:36.885 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:36 rene-pi qobuz-connect[2070]: 20260827 00:34:36.885 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:36 rene-pi qobuz-connect[2070]: 20260827 00:34:36.885 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:36 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:36 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:36 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:36 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:36 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:36 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:36 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:36 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:36 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:36.892+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=230470 volume=100 Aug 27 00:34:36 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:36.892+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title="Hazel Eyes" Aug 27 00:34:37 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:34:38 rene-pi qobuz-connect[2070]: 20260827 00:34:38.578 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: [Stream 1]: Audio stream has finished Aug 27 00:34:38 rene-pi qobuz-connect[2070]: 20260827 00:34:38.578 [2070.2070] INFO SampleApp: [Stream 1]: Audio stream finished Aug 27 00:34:38 rene-pi qobuz-connect[2070]: 20260827 00:34:38.578 [2070.2070] INFO SampleApp: Playback is nearly finished Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.789 [2070.2070] INFO SampleApp: Playback has finished Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.789 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: [Stream 1]: Disposing of audio stream Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.789 [2070.2070] INFO ContentFetcher: [0xa647a0]: Canceling download Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.790 [2070.2070] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.790 [2070.2070] INFO StreamingEventReporter: [0xa5f598]: Reporting streaming ended event Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.790 [2070.2070] INFO HttpDownloader: [0xa5f5f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.790 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.790 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.790 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.791 [2070.2070] INFO StreamingEventReporter: [0xa5f598]: Reporting streaming started event Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.791 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.791 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.791 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.800+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=1 volume=100 Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.800+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.801+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=1 volume=100 Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.801+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:39 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:39 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.815+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=2 volume=100 Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.816+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.816+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=2 volume=100 Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.817+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.817+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=2 volume=100 Aug 27 00:34:39 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:39.818+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Received SetState message: Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Playing state: Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Playback position: Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Queue version: 4.1 Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Current track: Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Next track: TID: 445382940, QID: 3, Context UUID: f43022a5-17ea-4598-a6f1-3d5a74683241 Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO MediaEngine: [0xa5ec10]: Setting next track: 445382940 Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: New stream: 3 Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO HttpDownloader: [0xb57358]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e426e098b0871b01e9d908483b19f663&request_ts=1787783679&track_id=445382940 Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO HttpDownloader: [0xb5cec0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=445382940 Aug 27 00:34:39 rene-pi qobuz-connect[2070]: 20260827 00:34:39.834 [2070.2070] INFO HttpDownloader: [0xbee9b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=21ce726167c05c20e2bb41765108814a&request_ts=1787783679&track_id=445382940 Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.011 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Received SetState message: Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.011 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Playing state: Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.011 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Playback position: Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.011 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Queue version: 4.1 Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.011 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Current track: Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.011 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Next track: TID: 445382940, QID: 3, Context UUID: f43022a5-17ea-4598-a6f1-3d5a74683241 Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.118 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2150301&eid=445382940&fmt=7&profile=raw&app_id=174516466&cid=1512846&etsp=1787787280&hmac=Bk02aueuBprG1D8ZDEgiuOCTIEk Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.139 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: [Stream 3]: stream information have been fetched Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.150 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: [Stream 3]: Metadata became available: Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.150 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: Title: My Guy Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.150 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: Artist: Sam Smith Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.150 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: Album: Hazel Eyes Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.150 [2070.2070] INFO AudioStreamManager: [0xa5ecc0]: Album art URL: https://static.qobuz.com/images/covers/ve/dj/vafgxaiq1djve_600.jpg Aug 27 00:34:40 rene-pi qobuz-connect[2070]: 20260827 00:34:40.168 [2070.2070] INFO HttpDownloader: [0xa5f5f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Aug 27 00:34:45 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:34:49 rene-pi qobuz-connect[2070]: 20260827 00:34:49.791 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:49 rene-pi qobuz-connect[2070]: 20260827 00:34:49.791 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:49 rene-pi qobuz-connect[2070]: 20260827 00:34:49.791 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:49 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:49 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:49 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:49 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:49 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:49 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:49 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:49 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:49 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:49.797+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=10002 volume=100 Aug 27 00:34:49 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:49.797+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:34:54 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:34:59 rene-pi qobuz-connect[2070]: 20260827 00:34:59.791 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:34:59 rene-pi qobuz-connect[2070]: 20260827 00:34:59.791 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:34:59 rene-pi qobuz-connect[2070]: 20260827 00:34:59.791 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:34:59 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:59 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:34:59 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:34:59 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:34:59 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:34:59 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:34:59 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:34:59 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:34:59 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:59.797+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=20002 volume=100 Aug 27 00:34:59 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:34:59.799+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:35:02 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:35:05 rene-pi volumio[1435]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/pi_screen_setup/volumio/bookworm/armhf Aug 27 00:35:05 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:05.389+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:06 rene-pi volumio[1435]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/pi_screen_setup/volumio/bookworm/armhf Aug 27 00:35:06 rene-pi volumio[1435]: info: Folder /tmp/plugins removed Aug 27 00:35:06 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:06.943+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:07 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:07.166+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:07 rene-pi volumio[1435]: info: Check plugin dependencies Aug 27 00:35:07 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 00:35:07 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:07.210+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:07 rene-pi volumio[1435]: info: Checking if plugin already exists Aug 27 00:35:07 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:07.213+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:07 rene-pi volumio[1435]: info: Rename folder Aug 27 00:35:07 rene-pi volumio[1435]: info: Folder /tmp/downloaded_plugin.zip removed Aug 27 00:35:07 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:07.230+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:07 rene-pi volumio[1435]: info: Move to category Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.098+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Checking if install.sh is present Aug 27 00:35:08 rene-pi volumio[1435]: info: Executing install.sh Aug 27 00:35:08 rene-pi sudo[7599]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/pi_screen_setup/install.sh Aug 27 00:35:08 rene-pi sudo[7599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 00:35:08 rene-pi volumio[1435]: info: Installing pi_screen_setup... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.168+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Detected: Raspberry Pi 4 Model B Rev 1.5 Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.173+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Creating sudoers entry for pi_screen_setup... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.175+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: /etc/sudoers.d/volumio-user-pi_screen_setup: parsed OK Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.197+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Sudoers configuration complete. Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.204+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Creating data directories... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.206+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Boot partition verified. Aug 27 00:35:08 rene-pi volumio[1435]: info: Checking factory backups... Aug 27 00:35:08 rene-pi volumio[1435]: info: Creating factory backup of config.txt... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.247+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.249+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.249+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Creating factory backup of cmdline.txt... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.255+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Creating factory backup of volumioconfig.txt... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.257+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Creating factory backup of userconfig.txt... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.264+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Factory backups created. Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.270+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Checking for existing display configuration... Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.273+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Found display settings in volumioconfig.txt Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.279+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.287+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: NOTE: Existing display configuration detected. Aug 27 00:35:08 rene-pi volumio[1435]: info: The plugin will offer to migrate these settings on first use. Aug 27 00:35:08 rene-pi volumio[1435]: info: Aug 27 00:35:08 rene-pi sudo[7599]: pam_unix(sudo:session): session closed for user root Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.291+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.291+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.291+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Install script completed Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.298+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Adding reference to registry Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.301+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Done installing plugin. Aug 27 00:35:08 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 00:35:08 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 00:35:08 rene-pi volumio[1435]: info: Folder /tmp/plugins removed Aug 27 00:35:08 rene-pi volumio[1435]: info: Folder /tmp/downloaded_plugin.zip removed Aug 27 00:35:08 rene-pi volumio[1435]: info: Created placeholder /boot/videoconfig.txt Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.317+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:08 rene-pi volumio[1435]: info: Installation complete. Aug 27 00:35:08 rene-pi volumio[1435]: Plugin install end detected on script Aug 27 00:35:08 rene-pi volumio[1435]: info: Folder /data/temp removed Aug 27 00:35:08 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:08.326+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 00:35:09 rene-pi volumio[1435]: info: Enabling plugin pi_screen_setup Aug 27 00:35:09 rene-pi volumio[1435]: info: Loading plugin "pi_screen_setup"... Aug 27 00:35:09 rene-pi qobuz-connect[2070]: 20260827 00:35:09.791 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:35:09 rene-pi qobuz-connect[2070]: 20260827 00:35:09.791 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:35:09 rene-pi qobuz-connect[2070]: 20260827 00:35:09.791 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:35:10 rene-pi volumio[1435]: info: PLUGIN START: pi_screen_setup Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Starting plugin Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Loaded bundled presets v1.5.9 Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Active presets source: bundled, 231 presets loaded Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Detected model: Raspberry Pi 4 Model B Rev 1.5 Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Detected SoC: bcm2711 Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Detected RAM: 7844 MB Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 4","model_raw":"Raspberry Pi 4 Model B Rev 1.5","soc":"bcm2711","ram_mb":7844,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi4","is_pi":true} Aug 27 00:35:10 rene-pi volumio[1435]: info: pi_screen_setup: Management server started on port 4567 Aug 27 00:35:10 rene-pi volumio[1435]: info: Done. Aug 27 00:35:10 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:35:10 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:35:10 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:35:10 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:35:10 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:35:10 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:35:10 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:35:10 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:35:10 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:10.165+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=30002 volume=100 Aug 27 00:35:10 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:10.165+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:35:11 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:35:14 rene-pi volumio[1435]: info: Enabling plugin pi_screen_setup Aug 27 00:35:14 rene-pi volumio[1435]: info: Loading plugin "pi_screen_setup"... Aug 27 00:35:14 rene-pi volumio[1435]: info: PLUGIN START: pi_screen_setup Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Starting plugin Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Loaded bundled presets v1.5.9 Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Active presets source: bundled, 231 presets loaded Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Detected model: Raspberry Pi 4 Model B Rev 1.5 Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Detected SoC: bcm2711 Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Detected RAM: 7844 MB Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 4","model_raw":"Raspberry Pi 4 Model B Rev 1.5","soc":"bcm2711","ram_mb":7844,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi4","is_pi":true} Aug 27 00:35:14 rene-pi volumio[1435]: error: pi_screen_setup: Port 4567 already in use Aug 27 00:35:14 rene-pi volumio[1435]: info: Done. Aug 27 00:35:14 rene-pi volumio[1435]: info: Enabling plugin pi_screen_setup Aug 27 00:35:14 rene-pi volumio[1435]: info: Loading plugin "pi_screen_setup"... Aug 27 00:35:14 rene-pi volumio[1435]: info: PLUGIN START: pi_screen_setup Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Starting plugin Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Loaded bundled presets v1.5.9 Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Active presets source: bundled, 231 presets loaded Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Detected model: Raspberry Pi 4 Model B Rev 1.5 Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Detected SoC: bcm2711 Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Detected RAM: 7844 MB Aug 27 00:35:14 rene-pi volumio[1435]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 4","model_raw":"Raspberry Pi 4 Model B Rev 1.5","soc":"bcm2711","ram_mb":7844,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi4","is_pi":true} Aug 27 00:35:14 rene-pi volumio[1435]: error: pi_screen_setup: Port 4567 already in use Aug 27 00:35:14 rene-pi volumio[1435]: info: Done. Aug 27 00:35:15 rene-pi volumio[1435]: info: Enabling plugin pi_screen_setup Aug 27 00:35:15 rene-pi volumio[1435]: info: Loading plugin "pi_screen_setup"... Aug 27 00:35:15 rene-pi volumio[1435]: info: PLUGIN START: pi_screen_setup Aug 27 00:35:15 rene-pi volumio[1435]: info: pi_screen_setup: Starting plugin Aug 27 00:35:15 rene-pi volumio[1435]: info: pi_screen_setup: Loaded bundled presets v1.5.9 Aug 27 00:35:15 rene-pi volumio[1435]: info: pi_screen_setup: Active presets source: bundled, 231 presets loaded Aug 27 00:35:15 rene-pi volumio[1435]: info: pi_screen_setup: Detected model: Raspberry Pi 4 Model B Rev 1.5 Aug 27 00:35:15 rene-pi volumio[1435]: info: pi_screen_setup: Detected SoC: bcm2711 Aug 27 00:35:15 rene-pi volumio[1435]: info: pi_screen_setup: Detected RAM: 7844 MB Aug 27 00:35:15 rene-pi volumio[1435]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 4","model_raw":"Raspberry Pi 4 Model B Rev 1.5","soc":"bcm2711","ram_mb":7844,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi4","is_pi":true} Aug 27 00:35:15 rene-pi volumio[1435]: error: pi_screen_setup: Port 4567 already in use Aug 27 00:35:15 rene-pi volumio[1435]: info: Done. Aug 27 00:35:18 rene-pi volumio[1435]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: getUIConfig - wizardStep=0, wizardComplete=false, migrationState=none (cache=none, stepCache=0) Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: getUIConfig - checkMigration=true Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: OTA drift section hidden=true (driftDetected=false, wizardComplete=false) Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Section 1 - default hidden, checkMigration=true, migrationState=none Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Checking for migration... Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Starting migration detection... Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: volumioconfig.txt content length: 814 Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Found 6 lines in volumioconfig.txt Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: userconfig.txt content length: 90 Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Found 0 lines in userconfig.txt: [] Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Migration detection complete - needed: true, lines: 6 Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Migration check result - needed=true, lines=6 Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Migration needed - showing migration section Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Migration display text: volumioconfig.txt: dtoverlay=vc4-kms-v3d | volumioconfig.txt: dtoverlay=vc4-kms-v3d | volumioconfig.txt: dtoverlay=vc4-kms-v3d | volumioconfig.txt: dtoverlay=vc4-kms-v3d | volumioconfig.txt: hdmi_force_hotplug=1 | volumioconfig.txt: display_auto_detect=1 Aug 27 00:35:19 rene-pi volumio[1435]: info: pi_screen_setup: Resolving uiconf with migration section visible Aug 27 00:35:19 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:35:19 rene-pi qobuz-connect[2070]: 20260827 00:35:19.791 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:35:19 rene-pi qobuz-connect[2070]: 20260827 00:35:19.791 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:35:19 rene-pi qobuz-connect[2070]: 20260827 00:35:19.791 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:35:19 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:35:19 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:35:19 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:35:19 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:35:19 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:35:19 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:35:19 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:35:19 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:35:19 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:19.798+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=40002 volume=100 Aug 27 00:35:19 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:19.799+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:35:27 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:35:29 rene-pi qobuz-connect[2070]: 20260827 00:35:29.791 [2070.2070] INFO ProtocolHandler: [0xa5f1e8]: Need to send state update Aug 27 00:35:29 rene-pi qobuz-connect[2070]: 20260827 00:35:29.791 [2070.2070] INFO QConnectMessageSender: [0xb88d40]: Sending StateUpdated message Aug 27 00:35:29 rene-pi qobuz-connect[2070]: 20260827 00:35:29.791 [2070.2070] INFO QwspMessageSender: [0xb5a888]: Sending Payload message Aug 27 00:35:29 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:35:29 rene-pi volumio[1435]: info: CoreCommandRouter::servicePushState Aug 27 00:35:29 rene-pi volumio[1435]: info: CoreStateMachine::pushState Aug 27 00:35:29 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 00:35:29 rene-pi volumio[1435]: info: CoreCommandRouter::volumioPushState Aug 27 00:35:29 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output update for this device Aug 27 00:35:29 rene-pi volumio[1435]: info: MRS: Pushing multiroomSync output Aug 27 00:35:29 rene-pi volumio[1435]: info: CoreCommandRouter::volumioGetState Aug 27 00:35:29 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:29.800+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" state=STATUS_PLAYING positionMs=50003 volume=100 Aug 27 00:35:29 rene-pi volumio5-onboarding[1953]: time=2026-08-27T00:35:29.801+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.183:50812,00:00:00:00:00:00%01 @ 0x2d9c9f0" id= title=Moondance Aug 27 00:35:34 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 00:35:34 rene-pi volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 00:35:35 rene-pi volumio[1435]: info: Discovery: Remote connection error: 192.168.178.142 Aug 27 00:35:36 rene-pi volumio[1435]: warn: [now-playing] MyBackgroundMonitor stopped Aug 27 00:35:36 rene-pi volumio[1435]: info: Disabling plugin now_playing Aug 27 00:35:36 rene-pi volumio[1435]: info: Done. Aug 27 00:35:38 rene-pi sudo[7678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 27 00:35:38 rene-pi sudo[7678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 00:35:38 rene-pi sudo[7678]: pam_unix(sudo:session): session closed for user root Aug 27 00:35:38 rene-pi sudo[7680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Aug 27 00:35:38 rene-pi sudo[7680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 00:35:38 rene-pi volumio[1435]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 27 00:35:38 rene-pi volumio[1435]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 00:35:38 rene-pi sudo[7683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Aug 27 00:35:38 rene-pi sudo[7683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 00:35:38 rene-pi volumio[1435]: TypeError: Cannot read properties of undefined (reading 'split') Aug 27 00:35:38 rene-pi volumio[1435]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Aug 27 00:35:38 rene-pi volumio[1435]: at ChildProcess.exithandler (node:child_process:413:7) Aug 27 00:35:38 rene-pi volumio[1435]: at ChildProcess.emit (node:events:514:28) Aug 27 00:35:38 rene-pi volumio[1435]: at maybeClose (node:internal/child_process:1105:16) Aug 27 00:35:38 rene-pi volumio[1435]: at Socket. (node:internal/child_process:457:11) Aug 27 00:35:38 rene-pi volumio[1435]: at Socket.emit (node:events:514:28) Aug 27 00:35:38 rene-pi volumio[1435]: at Pipe. (node:net:337:12) Aug 27 00:35:38 rene-pi volumio[1435]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 00:35:38 rene-pi sudo[7686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Aug 27 00:35:38 rene-pi sudo[7686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 00:35:38 rene-pi systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 27 00:35:38 rene-pi systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 27 00:35:38 rene-pi systemd[1]: Reloading. Aug 27 00:35:38 rene-pi sudo[7680]: pam_unix(sudo:session): session closed for user root Aug 27 00:35:39 rene-pi sudo[7722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 00:34' Aug 27 00:35:39 rene-pi sudo[7722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"