Feb 23 09:00:01 red-volumio qobuz-connect[1629]: 20260223 09:00:01.730 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Need to send state update Feb 23 09:00:01 red-volumio qobuz-connect[1629]: 20260223 09:00:01.730 [1629.1629] INFO QConnectMessageSender: [0x1d181a0]: Sending StateUpdated message Feb 23 09:00:01 red-volumio qobuz-connect[1629]: 20260223 09:00:01.730 [1629.1629] INFO QwspMessageSender: [0x1e4b320]: Sending Payload message Feb 23 09:00:01 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:01 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:01 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:01 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:01 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:01 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:01 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:01 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:01 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:01.757+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=189743 volume=10 Feb 23 09:00:01 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:01.757+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="If It's Magic" Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.381 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 7]: Running audio stream Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.381 [1629.1629] INFO UrlAudioSource: [0x1da8180]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10195270&eid=114154254&fmt=6&profile=raw&app_id=174516466&cid=3932927&etsp=1771837011&hmac=GqbCtvjBGLIzX9MMx4-WTdW-pFI Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.381 [1629.1629] INFO ContentFetcher: [0x20d92c0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10195270&eid=114154254&fmt=6&profile=raw&app_id=174516466&cid=3932927&etsp=1771837011&hmac=GqbCtvjBGLIzX9MMx4-WTdW-pFI, offset: 0 Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.382 [1629.1629] INFO SampleApp: [Stream 7]: New audio stream (starting from 0ms) Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.382 [1629.1629] INFO SampleApp: [Stream 7]: Stream metadata became available: Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.382 [1629.1629] INFO SampleApp: Title: Hey Lordy Mama Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.382 [1629.1629] INFO SampleApp: Artist: Abbey Lincoln Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.382 [1629.1629] INFO SampleApp: Album: A Turtle's Dream Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.382 [1629.1629] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/qc/r1/rc52gqoarr1qc_600.jpg Feb 23 09:00:09 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.581 [1629.1629] INFO UrlAudioSource: [0x1da8180]: Content info extracted Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.581 [1629.1629] INFO UrlAudioSource: [0x1da8180]: Size: 34053145 Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.584 [1629.3740] INFO AudioDecoder: [0x1f036f0]: Stream info: Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.584 [1629.3740] INFO AudioDecoder: [0x1f036f0]: Total samples: 19101180 Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.585 [1629.3740] INFO AudioDecoder: [0x1f036f0]: Sample rate: 44100 Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.585 [1629.3740] INFO AudioDecoder: [0x1f036f0]: Channel count: 2 Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.585 [1629.3740] INFO AudioDecoder: [0x1f036f0]: Bits per sample: 16 Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.585 [1629.3740] INFO AudioDecoder: [0x1f036f0]: Duration: 433133 Feb 23 09:00:09 red-volumio qobuz-connect[1629]: 20260223 09:00:09.587 [1629.1629] INFO UrlAudioSource: [0x1da8180]: Audio properties have changed Feb 23 09:00:11 red-volumio qobuz-connect[1629]: 20260223 09:00:11.731 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Need to send state update Feb 23 09:00:11 red-volumio qobuz-connect[1629]: 20260223 09:00:11.731 [1629.1629] INFO QConnectMessageSender: [0x1d181a0]: Sending StateUpdated message Feb 23 09:00:11 red-volumio qobuz-connect[1629]: 20260223 09:00:11.731 [1629.1629] INFO QwspMessageSender: [0x1e4b320]: Sending Payload message Feb 23 09:00:11 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:11 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:11 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:11 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:11 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:11 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:11 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:11 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:11 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:11.766+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=199743 volume=10 Feb 23 09:00:11 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:11.766+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="If It's Magic" Feb 23 09:00:18 red-volumio qobuz-connect[1629]: 20260223 09:00:18.321 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 6]: Audio stream has finished Feb 23 09:00:18 red-volumio qobuz-connect[1629]: 20260223 09:00:18.321 [1629.1629] INFO SampleApp: [Stream 6]: Audio stream finished Feb 23 09:00:18 red-volumio qobuz-connect[1629]: 20260223 09:00:18.321 [1629.1629] INFO SampleApp: Playback is nearly finished Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.642 [1629.1629] INFO SampleApp: Playback has finished Feb 23 09:00:19 red-volumio qobuz-connect[1629]: ALSA lib ./src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 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 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 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 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.694 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 6]: Disposing of audio stream Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.695 [1629.1629] INFO ContentFetcher: [0x2008680]: Canceling download Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.695 [1629.1629] INFO SampleApp: [Stream 6]: Audio stream has been disposed of Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.695 [1629.1629] INFO StreamingEventReporter: [0x1d15af8]: Reporting streaming ended event Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.696 [1629.1629] INFO HttpDownloader: [0x1d15b50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.696 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Need to send state update Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.696 [1629.1629] INFO QConnectMessageSender: [0x1d181a0]: Sending StateUpdated message Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.696 [1629.1629] INFO QwspMessageSender: [0x1e4b320]: Sending Payload message Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.697 [1629.1629] INFO QConnectMessageSender: [0x1d181a0]: Sending FileAudioQualityChanged message Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.697 [1629.1629] INFO QwspMessageSender: [0x1e4b320]: Sending Payload message Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.698 [1629.1629] INFO StreamingEventReporter: [0x1d15af8]: Reporting streaming started event Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.699 [1629.1629] INFO QConnectMessageSender: [0x1d181a0]: Sending DeviceAudioQualityChanged message Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.699 [1629.1629] INFO QwspMessageSender: [0x1e4b320]: Sending Payload message Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.723+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=10 Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.724+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="Hey Lordy Mama" Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:19 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:19 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.800+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=10 Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.802+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="Hey Lordy Mama" Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.804+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=10 Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.804+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="Hey Lordy Mama" Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.807+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=10 Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.808+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="Hey Lordy Mama" Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.811+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=10 Feb 23 09:00:19 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:19.814+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="Hey Lordy Mama" Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.827 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Received SetState message: Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.827 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Playing state: Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.827 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Playback position: Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.827 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Queue version: 38.2 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.827 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Current track: Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.827 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Next track: TID: 6005561, QID: 6, Context UUID: 38889970-6b2d-401d-83e4-45281afcf873 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.827 [1629.1629] INFO MediaEngine: [0x1d14fa0]: Setting next track: 6005561 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.828 [1629.1629] INFO AudioStreamManager: [0x1d15088]: New stream: 8 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.828 [1629.1629] INFO HttpDownloader: [0x1da3a78]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8c495a8682d5cc55ab31051fa8087ffc&request_ts=1771833619&track_id=6005561 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.828 [1629.1629] INFO HttpDownloader: [0x1dba8f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=6005561 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.828 [1629.1629] INFO HttpDownloader: [0x1da2128]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ed71429149b3532b0b7298698a83384b&request_ts=1771833619&track_id=6005561 Feb 23 09:00:19 red-volumio qobuz-connect[1629]: 20260223 09:00:19.966 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10195270&eid=6005561&fmt=27&profile=raw&app_id=174516466&cid=3932927&etsp=1771837219&hmac=O60VBAsc0s6-84yAwTzetFwRSb4 Feb 23 09:00:20 red-volumio qobuz-connect[1629]: 20260223 09:00:20.000 [1629.1629] INFO HttpDownloader: [0x1d15b50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Feb 23 09:00:20 red-volumio qobuz-connect[1629]: 20260223 09:00:20.008 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 8]: Metadata became available: Feb 23 09:00:20 red-volumio qobuz-connect[1629]: 20260223 09:00:20.009 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Title: The Thrill Is Gone (From Yesterday's Kiss) Feb 23 09:00:20 red-volumio qobuz-connect[1629]: 20260223 09:00:20.009 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Artist: Aretha Franklin Feb 23 09:00:20 red-volumio qobuz-connect[1629]: 20260223 09:00:20.009 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Album: Spirit in the Dark Feb 23 09:00:20 red-volumio qobuz-connect[1629]: 20260223 09:00:20.009 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Album art URL: https://static.qobuz.com/images/covers/49/35/0603497933549_600.jpg Feb 23 09:00:20 red-volumio qobuz-connect[1629]: 20260223 09:00:20.051 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 8]: stream information have been fetched Feb 23 09:00:29 red-volumio qobuz-connect[1629]: 20260223 09:00:29.696 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Need to send state update Feb 23 09:00:29 red-volumio qobuz-connect[1629]: 20260223 09:00:29.696 [1629.1629] INFO QConnectMessageSender: [0x1d181a0]: Sending StateUpdated message Feb 23 09:00:29 red-volumio qobuz-connect[1629]: 20260223 09:00:29.697 [1629.1629] INFO QwspMessageSender: [0x1e4b320]: Sending Payload message Feb 23 09:00:29 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:29 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:00:29 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:29 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:29 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:29 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:29 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:29 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:29 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:29.713+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=9255 volume=10 Feb 23 09:00:29 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:29.713+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id= title="Hey Lordy Mama" Feb 23 09:00:37 red-volumio volumio[979]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: Restoring Previous Volume level: 10 false true Feb 23 09:00:37 red-volumio volumio[979]: info: VolumeController::SetAlsaVolume100 Feb 23 09:00:37 red-volumio volumio[979]: info: Enable softmixer device for audio device number 5 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioStop Feb 23 09:00:37 red-volumio volumio[979]: info: CoreStateMachine::stop Feb 23 09:00:37 red-volumio volumio[979]: info: CoreStateMachine::serviceStop Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::serviceStop Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: Received stop Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.155 [1629.1629] INFO MediaEngine: [0x1d14fa0]: Stopping playback, keeping tracks Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.155 [1629.1629] INFO AudioRenderer: [0x1d151c0]: Stopping audio renderer Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.155 [1629.1629] INFO SampleApp: Stopping playback Feb 23 09:00:37 red-volumio volumio[979]: info: Enable softmixer device for audio device undefined Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.161 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Clearing all streams Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.161 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 7]: Disposing of audio stream Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.161 [1629.1629] INFO ContentFetcher: [0x20d92c0]: Canceling download Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.169 [1629.1629] INFO AudioStreamManager: [0x1d15088]: New stream: 9 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.170 [1629.1629] INFO HttpDownloader: [0x1da3a78]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=34b0ee134ab9bec5706d96f2a69fbc6d&request_ts=1771833637&track_id=114154254 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.170 [1629.1629] INFO HttpDownloader: [0x1da2f70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=114154254 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.170 [1629.1629] INFO HttpDownloader: [0x1da37b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4d28d3f36f514b7db3a44ab5031cc20a&request_ts=1771833637&track_id=114154254 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.171 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 9]: Running audio stream Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.171 [1629.1629] INFO AudioStreamManager: [0x1d15088]: New stream: 10 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.171 [1629.1629] INFO HttpDownloader: [0x1fa3040]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=93c3910d5ecc3b3ecc285ee458364a1b&request_ts=1771833637&track_id=6005561 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.171 [1629.1629] INFO HttpDownloader: [0x1eeabd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=6005561 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.171 [1629.1629] INFO HttpDownloader: [0x1dd4450]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=f4e2d4b57c03ece2d5e0a451115b3903&request_ts=1771833637&track_id=6005561 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.174 [1629.1629] INFO SampleApp: Playback state changed: Stopped Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.175 [1629.1629] INFO StreamingEventReporter: [0x1d15af8]: Reporting streaming ended event Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.175 [1629.1629] INFO HttpDownloader: [0x1d15b50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.175 [1629.1629] INFO ProtocolHandler: [0x1d156e0]: Need to send state update Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.175 [1629.1629] INFO QConnectMessageSender: [0x1d181a0]: Sending StateUpdated message Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.175 [1629.1629] INFO SampleApp: [Stream 7]: Audio stream has been disposed of Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.175 [1629.1629] INFO QwspMessageSender: [0x1e4b320]: Sending Payload message Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 23 09:00:37 red-volumio volumio[979]: info: Updating Volume Controller Parameters: Device: 5 Name: Holo Audio UAC2.0 Gen2 - RED Mixer: Holo Audio UAC2.0 Gen2 - RED Playback Swit Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 23 09:00:37 red-volumio volumio[979]: info: Disabling external Volume Control Feb 23 09:00:37 red-volumio volumio[979]: info: Output device has changed, restarting MPD Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.246 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 9]: Metadata became available: Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.246 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Title: Hey Lordy Mama Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.246 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Artist: Abbey Lincoln Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.246 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Album: A Turtle's Dream Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.246 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Album art URL: https://static.qobuz.com/images/covers/qc/r1/rc52gqoarr1qc_600.jpg Feb 23 09:00:37 red-volumio volumio[979]: info: Output device has changed, restarting Shairport Sync Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.261 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 10]: Metadata became available: Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.261 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Title: The Thrill Is Gone (From Yesterday's Kiss) Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.261 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Artist: Aretha Franklin Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.262 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Album: Spirit in the Dark Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.262 [1629.1629] INFO AudioStreamManager: [0x1d15088]: Album art URL: https://static.qobuz.com/images/covers/49/35/0603497933549_600.jpg Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.285 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 9]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10195270&eid=114154254&fmt=6&profile=raw&app_id=174516466&cid=3932927&etsp=1771837237&hmac=ugWaQqimbhQKbjdK_UDowgVBUoY Feb 23 09:00:37 red-volumio sudo[3781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 09:00:37 red-volumio sudo[3781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:37 red-volumio sudo[3783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 09:00:37 red-volumio sudo[3783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.296 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 10]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10195270&eid=6005561&fmt=27&profile=raw&app_id=174516466&cid=3932927&etsp=1771837237&hmac=T37xw7PQN3ZjqoqNKjec42SJx7w Feb 23 09:00:37 red-volumio sudo[3781]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:37 red-volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:00:37 red-volumio volumio[979]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: setDeactiveState invoked Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Feb 23 09:00:37 red-volumio volumio[979]: verbose: UNSET VOLATILE: Service: qobuzconnect Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: Received stop Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.347 [1629.1629] INFO MediaEngine: [0x1d14fa0]: Stopping playback, keeping tracks Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.347 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 9]: stream information have been fetched Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.347 [1629.1629] INFO UrlAudioSource: [0x1f41160]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10195270&eid=114154254&fmt=6&profile=raw&app_id=174516466&cid=3932927&etsp=1771837237&hmac=ugWaQqimbhQKbjdK_UDowgVBUoY Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.347 [1629.1629] INFO ContentFetcher: [0x1f44558]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10195270&eid=114154254&fmt=6&profile=raw&app_id=174516466&cid=3932927&etsp=1771837237&hmac=ugWaQqimbhQKbjdK_UDowgVBUoY, offset: 0 Feb 23 09:00:37 red-volumio volumio[979]: info: Volume configurations have been set Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: setDeactiveState invoked Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.348 [1629.1629] INFO SampleApp: [Stream 9]: New audio stream (starting from 0ms) Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.348 [1629.1629] INFO SampleApp: [Stream 9]: Stream metadata became available: Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.349 [1629.1629] INFO SampleApp: Title: Hey Lordy Mama Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.349 [1629.1629] INFO SampleApp: Artist: Abbey Lincoln Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.349 [1629.1629] INFO SampleApp: Album: A Turtle's Dream Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.349 [1629.1629] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/qc/r1/rc52gqoarr1qc_600.jpg Feb 23 09:00:37 red-volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.380 [1629.1629] INFO UrlAudioSource: [0x1f41160]: Content info extracted Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.380 [1629.1629] INFO UrlAudioSource: [0x1f41160]: Size: 34053145 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.381 [1629.3794] INFO AudioDecoder: [0x1d1ec98]: Stream info: Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.381 [1629.3794] INFO AudioDecoder: [0x1d1ec98]: Total samples: 19101180 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.381 [1629.3794] INFO AudioDecoder: [0x1d1ec98]: Sample rate: 44100 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.381 [1629.3794] INFO AudioDecoder: [0x1d1ec98]: Channel count: 2 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.381 [1629.3794] INFO AudioDecoder: [0x1d1ec98]: Bits per sample: 16 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.381 [1629.3794] INFO AudioDecoder: [0x1d1ec98]: Duration: 433133 Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.384 [1629.1629] INFO UrlAudioSource: [0x1f41160]: Audio properties have changed Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.389 [1629.1629] INFO AudioStreamManager: [0x1d15088]: [Stream 10]: stream information have been fetched Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 23 09:00:37 red-volumio volumio[979]: info: Updating Volume Controller Parameters: Device: 5 Name: Holo Audio UAC2.0 Gen2 - RED Mixer: Holo Audio UAC2.0 Gen2 - RED Playback Swit Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 23 09:00:37 red-volumio volumio[979]: info: Disabling external Volume Control Feb 23 09:00:37 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 09:00:37 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 09:00:37 red-volumio systemd[1]: mpd.service: Consumed 3.125s CPU time. Feb 23 09:00:37 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 09:00:37 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 09:00:37 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 09:00:37 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:37 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:37 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:37.481+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=100 Feb 23 09:00:37 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:37.483+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:00:37 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 09:00:37 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 09:00:37 red-volumio sudo[3810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 23 09:00:37 red-volumio sudo[3810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: playback_stop received, deferred solved Feb 23 09:00:37 red-volumio volumio[979]: info: QobuzConnect: playback_stop received, deferred solved Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:37 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:37 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:37.635+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume= Feb 23 09:00:37 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:37.635+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:00:37 red-volumio sudo[3810]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:37 red-volumio sudo[3817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 23 09:00:37 red-volumio sudo[3817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:37 red-volumio volumio[979]: info: MPD Permissions set Feb 23 09:00:37 red-volumio volumio[979]: Unable to parse: Feb 23 09:00:37 red-volumio volumio[979]: Simple mixer control 'Holo Audio UAC2.0 Gen2 - RED Playback Swit',0 Feb 23 09:00:37 red-volumio volumio[979]: Capabilities: pswitch Feb 23 09:00:37 red-volumio volumio[979]: Playback channels: Front Left - Front Right Feb 23 09:00:37 red-volumio volumio[979]: Mono: Feb 23 09:00:37 red-volumio volumio[979]: Front Left: Playback [on] Feb 23 09:00:37 red-volumio volumio[979]: Front Right: Playback [on] Feb 23 09:00:37 red-volumio volumio[979]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 23 09:00:37 red-volumio sudo[3818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 23 09:00:37 red-volumio sudo[3818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:37 red-volumio volumio[979]: info: Software Volume ALSA configuration written Feb 23 09:00:37 red-volumio volumio[979]: info: Preparing to generate the ALSA configuration file Feb 23 09:00:37 red-volumio volumio[979]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 23 09:00:37 red-volumio volumio[979]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 23 09:00:37 red-volumio volumio[979]: info: Reading ALSA contributions from plugins. Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio sudo[3817]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.811 [1629.1629] INFO ContentFetcher: [0x1f44558]: Canceling download Feb 23 09:00:37 red-volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.825 [1629.1629] INFO CloudClient: [0x1d15578]: Disconnecting from the cloud Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.826 [1629.1629] INFO CloudClient: [0x1d15578]: Connection closed Feb 23 09:00:37 red-volumio sudo[3809]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 09:00:37 red-volumio sudo[3809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:37 red-volumio sudo[3824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 23 09:00:37 red-volumio sudo[3824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:37 red-volumio sudo[3809]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:37 red-volumio qobuz-connect[1629]: 20260223 09:00:37.858 [1629.1629] INFO SampleApp: Stopping Local configuration server Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 23 09:00:37 red-volumio volumio[979]: Unable to parse: Feb 23 09:00:37 red-volumio volumio[979]: Simple mixer control 'Holo Audio UAC2.0 Gen2 - RED Playback Swit',0 Feb 23 09:00:37 red-volumio volumio[979]: Capabilities: pswitch Feb 23 09:00:37 red-volumio volumio[979]: Playback channels: Front Left - Front Right Feb 23 09:00:37 red-volumio volumio[979]: Mono: Feb 23 09:00:37 red-volumio volumio[979]: Front Left: Playback [on] Feb 23 09:00:37 red-volumio volumio[979]: Front Right: Playback [on] Feb 23 09:00:37 red-volumio volumio[979]: info: VolumeController:: Volume=undefined Mute =false Feb 23 09:00:37 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:37 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:37 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:38 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:38 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:38 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:38 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:38 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:38.006+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume= Feb 23 09:00:38 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:38.007+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:00:38 red-volumio volumio[979]: info: Starting Shairport Sync Feb 23 09:00:38 red-volumio volumio[979]: info: Asound.conf file written Feb 23 09:00:38 red-volumio sudo[3839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 09:00:38 red-volumio sudo[3839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:38 red-volumio sudo[3843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 23 09:00:38 red-volumio sudo[3843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:38 red-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 09:00:38 red-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 09:00:38 red-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 09:00:38 red-volumio systemd[1]: shairport-sync.service: Consumed 7.580s CPU time. Feb 23 09:00:38 red-volumio sudo[3843]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:38 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 23 09:00:38 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 23 09:00:38 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 23 09:00:38 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 23 09:00:38 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 23 09:00:38 red-volumio volumio[979]: info: Output device has changed, restarting MPD Feb 23 09:00:38 red-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 09:00:38 red-volumio sudo[3839]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:38 red-volumio sudo[3852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 09:00:38 red-volumio volumio[979]: info: Output device has changed, restarting Shairport Sync Feb 23 09:00:38 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:38 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:00:38 red-volumio sudo[3852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:38 red-volumio sudo[3852]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:38 red-volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:00:38 red-volumio volumio[979]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 23 09:00:38 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:38 red-volumio sudo[3854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 09:00:38 red-volumio sudo[3854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:38 red-volumio volumio[979]: info: QobuzConnect: setDeactiveState invoked Feb 23 09:00:38 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:38 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:38 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 09:00:38 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 09:00:38 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 09:00:38 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 09:00:38 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 09:00:38 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 09:00:38 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 09:00:38 red-volumio sudo[3881]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 09:00:38 red-volumio sudo[3881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 09:00:38 red-volumio sudo[3881]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:39 red-volumio volumio[979]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 23 09:00:39 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 23 09:00:39 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 23 09:00:39 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 23 09:00:39 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 23 09:00:39 red-volumio volumio[979]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 23 09:00:39 red-volumio volumio[979]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:87c0" "" "" Feb 23 09:00:39 red-volumio volumio[979]: Hardware is initialized using a generic method Feb 23 09:00:39 red-volumio volumio[979]: info: QobuzConnect: setDeactiveState invoked Feb 23 09:00:39 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:39 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:39 red-volumio volumio[979]: info: Output device has changed, restarting MPD Feb 23 09:00:39 red-volumio volumio[979]: info: Output device has changed, restarting Shairport Sync Feb 23 09:00:39 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:39 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:00:39 red-volumio sudo[3902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 09:00:39 red-volumio qobuz-connect[1629]: 20260223 09:00:39.707 [1629.1629] INFO SampleApp: shat down connection on UNIX socket Feb 23 09:00:39 red-volumio sudo[3902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:39 red-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 23 09:00:39 red-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:39 red-volumio systemd[1]: qobuz-connect.service: Consumed 54.848s CPU time. Feb 23 09:00:39 red-volumio sudo[3902]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:39 red-volumio sudo[3905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 09:00:39 red-volumio sudo[3905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:39 red-volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:00:39 red-volumio volumio[979]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 23 09:00:39 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:39 red-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:39 red-volumio sudo[3818]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:39 red-volumio sudo[3824]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:39 red-volumio volumio[979]: info: QobuzConnect: setDeactiveState invoked Feb 23 09:00:39 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:39 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:39 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 09:00:39 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 09:00:39 red-volumio systemd[1]: mpd.service: Consumed 1.093s CPU time. Feb 23 09:00:39 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 09:00:39 red-volumio volumio[979]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 23 09:00:39 red-volumio volumio[979]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 23 09:00:39 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 09:00:39 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 09:00:39 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 23 09:00:39 red-volumio volumio[979]: info: Disabling external Volume Control Feb 23 09:00:40 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 09:00:40 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 09:00:40 red-volumio sudo[3930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 23 09:00:40 red-volumio sudo[3930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio sudo[3938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 23 09:00:40 red-volumio sudo[3938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio sudo[3930]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:40 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:40 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:40 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:40 red-volumio sudo[3938]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:40 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:40 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:40.189+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=100 Feb 23 09:00:40 red-volumio sudo[3945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 23 09:00:40 red-volumio sudo[3945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:40.196+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:00:40 red-volumio volumio[979]: info: MPD Permissions set Feb 23 09:00:40 red-volumio volumio[979]: info: MPD Permissions set Feb 23 09:00:40 red-volumio volumio[979]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 23 09:00:40 red-volumio volumio[979]: info: QobuzConnect: setDeactiveState invoked Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:40 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:40 red-volumio volumio[979]: info: Shairport-Sync Started Feb 23 09:00:40 red-volumio sudo[3946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 23 09:00:40 red-volumio sudo[3946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio volumio[979]: info: Executing endpoint qc_getconfig Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 23 09:00:40 red-volumio sudo[3947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 23 09:00:40 red-volumio sudo[3947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 23 09:00:40 red-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 23 09:00:40 red-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:40 red-volumio sudo[3935]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 09:00:40 red-volumio sudo[3935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 09:00:40 red-volumio sudo[3945]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio sudo[3935]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:00:40 red-volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 23 09:00:40 red-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 23 09:00:40 red-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:40 red-volumio volumio[979]: info: Executing endpoint qc_getconfig Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 23 09:00:40 red-volumio sudo[3946]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio volumio[979]: info: Starting Shairport Sync Feb 23 09:00:40 red-volumio sudo[3969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 23 09:00:40 red-volumio sudo[3969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio volumio[979]: info: Starting Shairport Sync Feb 23 09:00:40 red-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:40 red-volumio sudo[3947]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 23 09:00:40 red-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 23 09:00:40 red-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:40 red-volumio sudo[3978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 09:00:40 red-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 09:00:40 red-volumio sudo[3978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio sudo[3976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 09:00:40 red-volumio sudo[3976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:40 red-volumio sudo[3969]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio volumio[979]: info: Executing endpoint qc_getconfig Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 23 09:00:40 red-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 09:00:40 red-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 09:00:40 red-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 09:00:40 red-volumio systemd[1]: shairport-sync.service: Consumed 1.597s CPU time. Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.763 [3979.3979] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 23 09:00:40 red-volumio volumio[979]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 23 09:00:40 red-volumio volumio[979]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.777 [3979.3979] INFO VolumeManager: [0x1de408]: Setting new playback volume: 75 Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.778 [3979.3979] INFO VolumeManager: [0x1de408]: Setting new mute state: 0 Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.778 [3979.3979] INFO QobuzConnect: [0x1dedd8]: Client initialized! Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.778 [3979.3979] INFO SampleApp: Starting Avahi advertising, name: Red Volumio, service name: _qobuz-connect._tcp Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.802 [3979.3979] INFO LocalConfigManager: [0x1ddce8]: Starting Local Configuration server Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.803 [3979.3979] INFO SampleApp: Starting Local configuration server Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.804 [3979.3979] INFO SampleApp: Connected to UNIX socket client 0x1c8818 Feb 23 09:00:40 red-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 09:00:40 red-volumio sudo[3976]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio sudo[3978]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:40 red-volumio volumio[979]: info: Shairport-Sync Started Feb 23 09:00:40 red-volumio volumio[979]: info: Shairport-Sync Started Feb 23 09:00:40 red-volumio qobuz-connect[3979]: 20260223 09:00:40.913 [3979.3979] INFO SampleApp: Playback volume changed: 75 Feb 23 09:00:40 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:40 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:41 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 23 09:00:41 red-volumio sudo[4005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 09:00:41 red-volumio sudo[4005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 09:00:41 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 09:00:41 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 09:00:41 red-volumio systemd[1]: mpd.service: Consumed 1.468s CPU time. Feb 23 09:00:41 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 09:00:41 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 09:00:41 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 09:00:41 red-volumio volumio[979]: info: VolumeController::SetAlsaVolume10 Feb 23 09:00:41 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:00:41 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 09:00:41 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:41 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:00:41 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:00:41 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 09:00:41 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:00:41 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:00:41 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:00:41 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:00:41 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:41.713+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=10 Feb 23 09:00:41 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:00:41.714+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:00:41 red-volumio qobuz-connect[3979]: 20260223 09:00:41.783 [3979.3987] INFO SampleApp: API endpoint invoked: get-display-info Feb 23 09:00:41 red-volumio sudo[4009]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 09:00:41 red-volumio sudo[4009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 09:00:41 red-volumio sudo[4009]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:44 red-volumio mpd[4011]: 2026-02-23T09:00:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 09:00:44 red-volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 09:00:44 red-volumio sudo[3905]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:44 red-volumio sudo[4005]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:44 red-volumio sudo[3783]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:44 red-volumio sudo[3854]: pam_unix(sudo:session): session closed for user root Feb 23 09:00:44 red-volumio volumio[979]: error: MPD error: The expression evaluated to a falsy value: Feb 23 09:00:44 red-volumio volumio[979]: assert.ok(self.idling) Feb 23 09:00:44 red-volumio volumio[979]: error: The expression evaluated to a falsy value: Feb 23 09:00:44 red-volumio volumio[979]: assert.ok(self.idling) Feb 23 09:00:44 red-volumio volumio[979]: error: MPD error: The expression evaluated to a falsy value: Feb 23 09:00:44 red-volumio volumio[979]: assert.ok(self.idling) Feb 23 09:00:44 red-volumio volumio[979]: error: The expression evaluated to a falsy value: Feb 23 09:00:44 red-volumio volumio[979]: assert.ok(self.idling) Feb 23 09:00:44 red-volumio volumio[979]: error: MPD error: The expression evaluated to a falsy value: Feb 23 09:00:44 red-volumio volumio[979]: assert.ok(self.idling) Feb 23 09:00:44 red-volumio volumio[979]: error: The expression evaluated to a falsy value: Feb 23 09:00:44 red-volumio volumio[979]: assert.ok(self.idling) Feb 23 09:00:44 red-volumio volumio[979]: error: updateQueue error: null Feb 23 09:01:00 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:01:00 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:09 red-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 23 09:01:09 red-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 23 09:01:09 red-volumio systemd[1]: welcome.service: Deactivated successfully. Feb 23 09:01:09 red-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 23 09:01:09 red-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 23 09:01:09 red-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 23 09:01:09 red-volumio welcome[4074]: Resolved ip:[1] 192.168.1.177 Feb 23 09:01:09 red-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 23 09:01:09 red-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 23 09:01:09 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:09.815+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.55:52293 @ 0x1b40810" latency=-499.674µs timeout=3s volume=19 Feb 23 09:01:09 red-volumio volumio[979]: info: VolumeController::SetAlsaVolume19 Feb 23 09:01:09 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:01:09 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:09 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:01:09 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:01:09 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:01:09 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:01:09 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:01:09 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:09 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:09.849+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=19 Feb 23 09:01:09 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:09.850+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:01:10 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:10.111+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.55:52293 @ 0x1b40810" latency=-6.476429ms timeout=3s volume=24 Feb 23 09:01:10 red-volumio volumio[979]: info: VolumeController::SetAlsaVolume24 Feb 23 09:01:10 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:01:10 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:01:10 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:10.133+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" available=true connected=true macAddress=e4:5f:01:e2:a6:d3 ip4Address=192.168.1.177/24 ip6Address= Feb 23 09:01:10 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:01:10 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:01:10 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:10 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:10.150+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=24 Feb 23 09:01:10 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:10.151+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:01:10 red-volumio volumio[979]: info: Received Get System Info Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:01:10 red-volumio volumio[979]: info: Discovery: Getting this device information Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:01:10 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 09:01:10 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 09:01:13 red-volumio volumio[979]: info: CoreCommandRouter::volumioPause Feb 23 09:01:13 red-volumio volumio[979]: info: CoreStateMachine::pause Feb 23 09:01:13 red-volumio volumio[979]: info: CoreStateMachine::stPlaybackTimer Feb 23 09:01:13 red-volumio volumio[979]: info: CoreStateMachine::servicePause Feb 23 09:01:13 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:13 red-volumio volumio[979]: info: CoreCommandRouter::servicePause Feb 23 09:01:13 red-volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand pause Feb 23 09:01:13 red-volumio volumio[979]: info: sendMpdCommand pause took 3 milliseconds Feb 23 09:01:13 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:13 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:01:13 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:13 red-volumio volumio[979]: verbose: STATE SERVICE {"status":"pause","seek":0} Feb 23 09:01:13 red-volumio volumio[979]: verbose: CURRENT POSITION 0 Feb 23 09:01:13 red-volumio volumio[979]: info: CoreStateMachine::syncState stateService pause Feb 23 09:01:13 red-volumio volumio[979]: info: CoreStateMachine::syncState currentStatus pause Feb 23 09:01:13 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:01:13 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:13 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:01:13 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:01:13 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:01:13 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:01:13 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:01:13 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:13 red-volumio volumio[979]: info: CoreStateMachine::stPlaybackTimer Feb 23 09:01:13 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:13.041+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PAUSED positionMs=0 volume=24 Feb 23 09:01:13 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:13.042+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:01:15 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:01:15 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:15 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 23 09:01:15 red-volumio volumio[979]: info: CoreCommandRouter::volumioPlay Feb 23 09:01:15 red-volumio volumio[979]: info: CoreStateMachine::play index undefined Feb 23 09:01:15 red-volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 09:01:15 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:15 red-volumio volumio[979]: info: CoreStateMachine::startPlaybackTimer Feb 23 09:01:15 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:15 red-volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand play Feb 23 09:01:15 red-volumio volumio[979]: info: sendMpdCommand play took 1 milliseconds Feb 23 09:01:15 red-volumio volumio[979]: info: CoreCommandRouter::servicePushState Feb 23 09:01:15 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:15 red-volumio volumio[979]: verbose: STATE SERVICE {"status":"play","seek":0} Feb 23 09:01:15 red-volumio volumio[979]: verbose: CURRENT POSITION 0 Feb 23 09:01:15 red-volumio volumio[979]: info: CoreStateMachine::syncState stateService play Feb 23 09:01:15 red-volumio volumio[979]: info: CoreStateMachine::syncState currentStatus pause Feb 23 09:01:15 red-volumio volumio[979]: info: CoreStateMachine::pushState Feb 23 09:01:15 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:15 red-volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:01:15 red-volumio volumio[979]: info: CoreCommandRouter::volumioPushState Feb 23 09:01:15 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output update for this device Feb 23 09:01:15 red-volumio volumio[979]: info: MRS: Pushing multiroomSync output Feb 23 09:01:15 red-volumio volumio[979]: info: CoreCommandRouter::volumioGetState Feb 23 09:01:15 red-volumio volumio[979]: info: CorePlayQueue::getTrack 0 Feb 23 09:01:15 red-volumio volumio[979]: info: [1771833675847] [RadioParadise] getMetadata started with url undefined Feb 23 09:01:15 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:15.857+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" state=STATUS_PLAYING positionMs=0 volume=24 Feb 23 09:01:15 red-volumio volumio5-onboarding[1305]: time=2026-02-23T09:01:15.857+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:52293 @ 0x1b40810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Feb 23 09:01:15 red-volumio volumio[979]: info: [1771833675877] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Feb 23 09:01:15 red-volumio volumio[979]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 09:01:15 red-volumio volumio[979]: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Feb 23 09:01:15 red-volumio volumio[979]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:487:41) Feb 23 09:01:15 red-volumio volumio[979]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:458:14) Feb 23 09:01:15 red-volumio volumio[979]: at ClientRequest.emit (node:events:514:28) Feb 23 09:01:15 red-volumio volumio[979]: at TLSSocket.socketErrorListener (node:_http_client:495:9) Feb 23 09:01:15 red-volumio volumio[979]: at TLSSocket.emit (node:events:514:28) Feb 23 09:01:15 red-volumio volumio[979]: at emitErrorNT (node:internal/streams/destroy:151:8) Feb 23 09:01:15 red-volumio volumio[979]: at emitErrorCloseNT (node:internal/streams/destroy:116:3) Feb 23 09:01:15 red-volumio volumio[979]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Feb 23 09:01:15 red-volumio volumio[979]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 09:01:16 red-volumio sudo[4099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 09:00' Feb 23 09:01:16 red-volumio sudo[4099]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"