Mar 04 18:23:02 volumiobadet qobuz-connect[2431]: 20260304 18:23:02.273 [2431.2435] INFO SampleApp: API endpoint invoked: get-display-info Mar 04 18:23:02 volumiobadet qobuz-connect[2431]: 20260304 18:23:02.438 [2431.2435] INFO SampleApp: API endpoint invoked: get-connect-info Mar 04 18:23:11 volumiobadet wpa_supplicant[1066]: RRM: Ignoring radio measurement request: Not RRM network Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.962 [2431.2435] INFO SampleApp: API endpoint invoked: connect-to-qconnect Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.964 [2431.2431] INFO EndpointManager: [0x1e6a738]: Updating API endpoint Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.964 [2431.2431] INFO EndpointManager: [0x1e6a738]: Updating QConnect endpoint Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.964 [2431.2431] INFO ActiveStateManager: [0x1e69750]: Setting new active state: active Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.964 [2431.2431] INFO PlaybackSessionManager: [0x1e6ab28]: Starting playback session maintenance Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.965 [2431.2431] INFO HttpDownloader: [0x1e6ad18]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.965 [2431.2431] INFO CloudClient: [0x1e6b398]: Connecting to the cloud Mar 04 18:23:12 volumiobadet qobuz-connect[2431]: 20260304 18:23:12.966 [2431.2431] INFO SampleApp: Renderer is now active Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO CloudClient: [0x1e6b398]: Connection established Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Authenticate message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Subscribe message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending JoinSession message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending VolumeChanged message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending VolumeMuted message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending MaxAudioQualityChanged message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.217 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Payload message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.231 [2431.2431] INFO PlaybackSessionManager: [0x1e6ab28]: Playback session has been refreshed Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Received SetActive message: active Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Received SetState message: Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Playing state: Paused Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Playback position: 1242 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Queue version: 35.3 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Current track: TID: 381230934, QID: 0, Context UUID: c5e8bc56-7c63-43e8-b348-fe0105745834 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Next track: TID: 25427604, QID: 1, Context UUID: c5e8bc56-7c63-43e8-b348-fe0105745834 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO MediaEngine: [0x1e6adc0]: Stopping playback, clearing tracks Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO MediaEngine: [0x1e6adc0]: Initiating playback Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO RendererActionAvailabilityManager: [0x1e6b450]: Renderer action 'Next' is available Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.282 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Received SetLoopMode message: Off Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO PlaybackControlsManager: [0x1e6b198]: Setting new loop mode: Off Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO MediaEngine: [0x1e6adc0]: Setting current track: 381230934, initial offset: 1242ms Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Clearing all streams Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: New stream: 1 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO HttpDownloader: [0x1e76c80]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d0a8b817ed77a6227540a042aec980c6&request_ts=1772644993&track_id=381230934 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO HttpDownloader: [0x1eaafc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=381230934 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO HttpDownloader: [0x1e71358]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=84305e84dbf99a7cfbff9a5e45d0f364&request_ts=1772644993&track_id=381230934 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: [Stream 1]: Running audio stream Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Received SetShuffleMode message: disabled Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.283 [2431.2431] INFO PlaybackControlsManager: [0x1e6b198]: Setting new shuffle mode: disabled Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.284 [2431.2431] INFO MediaEngine: [0x1e6adc0]: Setting next track: 25427604 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.285 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: New stream: 2 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.285 [2431.2431] INFO HttpDownloader: [0x1e760c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=2910f3a7ce360af26500b246603d0523&request_ts=1772644993&track_id=25427604 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.285 [2431.2431] INFO HttpDownloader: [0x1f51380]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25427604 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.285 [2431.2431] INFO HttpDownloader: [0x1f52400]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ece38b8416ade2a86c08fa2f1c735af0&request_ts=1772644993&track_id=25427604 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.286 [2431.2431] INFO MediaEngine: [0x1e6adc0]: Waiting for current stream to start before starting audio renderer Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.352 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: [Stream 2]: Metadata became available: Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.352 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Title: Elephants On Ice Skates Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.352 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Artist: Brian Bromberg Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.352 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Album: It Is What It Is Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.352 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Album art URL: https://static.qobuz.com/images/covers/27/19/0181475701927_600.jpg Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.396 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=381230934&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648593&hmac=FQwB7VF81i7SCvHJnI8SM8EmY0c Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.396 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=25427604&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648593&hmac=kgumXycCnWxWMfqzVJjnx94YRzQ Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.399 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: [Stream 1]: Metadata became available: Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.399 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Title: Silver Rain Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.399 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Artist: Marcus Miller Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.399 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Album: 3 Original Album Classics Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.399 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: Album art URL: https://static.qobuz.com/images/covers/w1/5l/yb1r63mce5lw1_600.jpg Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.445 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: [Stream 2]: stream information have been fetched Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.458 [2431.2431] INFO AudioStreamManager: [0x1e6aea8]: [Stream 1]: stream information have been fetched Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.458 [2431.2431] INFO UrlAudioSource: [0x201f450]: Starting URL audio source, initial position: 1242ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=381230934&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648593&hmac=FQwB7VF81i7SCvHJnI8SM8EmY0c Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO ContentFetcher: [0x1e71750]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=381230934&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648593&hmac=FQwB7VF81i7SCvHJnI8SM8EmY0c, offset: 0 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO AudioRenderer: [0x1e6afe0]: Starting audio renderer, initial playback state: Paused Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO SampleApp: [Stream 1]: New audio stream (starting from 1241ms) Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO SampleApp: [Stream 1]: Stream metadata became available: Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO SampleApp: Title: Silver Rain Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO SampleApp: Artist: Marcus Miller Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO SampleApp: Album: 3 Original Album Classics Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.459 [2431.2431] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/w1/5l/yb1r63mce5lw1_600.jpg Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: QobuzConnect: setActiveState() Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: QobuzConnect: Set Qobuz connect default volume to 18 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.462 [2431.2431] INFO VolumeManager: [0x1e6b228]: Setting new playback volume: 18 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: QobuzConnect: Stopping currently active service Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioStop Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::stop Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::stPlaybackTimer Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::updateTrackBlock Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrackBlock Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::serviceStop Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: ControllerMpd::stop Mar 04 18:23:13 volumiobadet volumio[1106]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 18:23:13 volumiobadet volumio[1106]: info: sendMpdCommand stop took 16 milliseconds Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:13 volumiobadet volumio[1106]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"Every Praise ((album edit))","artist":"Hezekiah Walker","album":"Every Praise ((album edit))","albumart":"https://static.qobuz.com/images/covers/25/97/0886443999725_600.jpg","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":0,"duration":442,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"stream":false,"repeatMode":"all"} Mar 04 18:23:13 volumiobadet volumio[1106]: verbose: CURRENT POSITION 0 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::syncState stateService stop Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::syncState currentStatus stop Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: No code Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Mar 04 18:23:13 volumiobadet volumio[1106]: info: QobuzConnect: setActiveState() completed, send playback confirmation Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.523 [2431.2431] INFO SampleApp: Initiating playback, initial state: Paused Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: ALSA lib ./src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioMultiRoom has buffer size 120422 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: ALSA lib ./src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioMultiRoom has buffer size 120422 Mar 04 18:23:13 volumiobadet snapclient[2295]: 2026-03-04 18-23-13.545 [Info] (Alsa) PCM name: volumioMultiRoomClient, sample rate: 48000 Hz, channels: 2, buffer time: 80000 us, periods: 4, period time: 20000 us, period frames: 960 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.662 [2431.2431] INFO SampleApp: Playback state changed: Paused Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.662 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Need to send state update Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.662 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending StateUpdated message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Payload message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending FileAudioQualityChanged message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Payload message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending DeviceAudioQualityChanged message Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Payload message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO SampleApp: Playback volume changed: 18 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Volume changed: 18 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.663 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending VolumeChanged message Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.664 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Payload message Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.685 [2431.2431] INFO UrlAudioSource: [0x201f450]: Content info extracted Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.685 [2431.2431] INFO UrlAudioSource: [0x201f450]: Size: 41135679 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.686 [2431.2517] INFO AudioDecoder: [0x205db80]: Stream info: Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.687 [2431.2517] INFO AudioDecoder: [0x205db80]: Total samples: 16145892 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.687 [2431.2517] INFO AudioDecoder: [0x205db80]: Sample rate: 44100 Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.687 [2431.2517] INFO AudioDecoder: [0x205db80]: Channel count: 2 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.687 [2431.2517] INFO AudioDecoder: [0x205db80]: Bits per sample: 16 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.687 [2431.2517] INFO AudioDecoder: [0x205db80]: Duration: 366120 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.688 [2431.2431] INFO UrlAudioSource: [0x201f450]: Audio properties have changed Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.688 [2431.2431] INFO UrlAudioSource: [0x201f450]: Seeking input stream to byte offset: 106602 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.688 [2431.2431] INFO ContentFetcher: [0x1e71750]: Adjusting download offset to: 106602 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet volumio[1106]: info: QobuzConnect: Volume received from daemon: 18 Mar 04 18:23:13 volumiobadet volumio[1106]: info: VolumeController::SetAlsaVolume18 Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:13 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:13 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.844 [2431.2431] INFO UrlAudioSource: [0x201f450]: Seeking input stream to byte offset: 80740 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.844 [2431.2431] INFO ContentFetcher: [0x1e71750]: Adjusting download offset to: 80740 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.987 [2431.2431] INFO UrlAudioSource: [0x201f450]: Seeking input stream to byte offset: 77309 Mar 04 18:23:13 volumiobadet qobuz-connect[2431]: 20260304 18:23:13.987 [2431.2431] INFO ContentFetcher: [0x1e71750]: Adjusting download offset to: 77309 Mar 04 18:23:14 volumiobadet qobuz-connect[2431]: 20260304 18:23:14.121 [2431.2431] INFO UrlAudioSource: [0x201f450]: Seeking input stream to byte offset: 68031 Mar 04 18:23:14 volumiobadet qobuz-connect[2431]: 20260304 18:23:14.121 [2431.2431] INFO ContentFetcher: [0x1e71750]: Adjusting download offset to: 68031 Mar 04 18:23:15 volumiobadet volumio[1106]: info: Executing endpoint metavolumio Mar 04 18:23:15 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 18:23:15 volumiobadet volumio[1106]: info: Executing endpoint metavolumio Mar 04 18:23:15 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 18:23:15 volumiobadet volumio[1106]: info: Executing endpoint metavolumio Mar 04 18:23:15 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 18:23:16 volumiobadet snapclient[2295]: 2026-03-04 18-23-16.816 [Info] (Stream) Exception: Not enough frames available, requested frames: 960, available: 806 Mar 04 18:23:16 volumiobadet snapclient[2295]: 2026-03-04 18-23-16.816 [Info] (Alsa) Failed to get chunk Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Received SetState message: Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Playing state: Playing Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Playback position: Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Queue version: Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Current track: Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Next track: Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO MediaEngine: [0x1e6adc0]: Resuming playback Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO AudioRenderer: [0x1e6afe0]: Resuming audio renderer Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO SampleApp: Resuming playback Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.795 [2431.2431] INFO SampleApp: Playback state changed: Playing Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.796 [2431.2431] INFO StreamingEventReporter: [0x1e6b918]: Reporting streaming started event Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.796 [2431.2431] INFO HttpDownloader: [0x1e6b970]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.796 [2431.2431] INFO ProtocolHandler: [0x1e6b500]: Need to send state update Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.796 [2431.2431] INFO QConnectMessageSender: [0x2000b68]: Sending StateUpdated message Mar 04 18:23:19 volumiobadet qobuz-connect[2431]: 20260304 18:23:19.796 [2431.2431] INFO QwspMessageSender: [0x1f6cd38]: Sending Payload message Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:19 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:19 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:20 volumiobadet systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Mar 04 18:23:20 volumiobadet systemd[1]: qobuz-connect.service: Failed with result 'signal'. Mar 04 18:23:20 volumiobadet volumio[1106]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 04 18:23:20 volumiobadet volumio[1106]: info: QobuzConnect: setDeactiveState invoked Mar 04 18:23:20 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:20 volumiobadet volumio[1106]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Mar 04 18:23:20 volumiobadet volumio[1106]: verbose: UNSET VOLATILE: Service: qobuzconnect Mar 04 18:23:20 volumiobadet volumio[1106]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Mar 04 18:23:20 volumiobadet volumio[1106]: info: QobuzConnect: Received stop Mar 04 18:23:20 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:20 volumiobadet systemd[1]: qobuz-connect.service: Consumed 1.150s CPU time. Mar 04 18:23:21 volumiobadet snapclient[2295]: 2026-03-04 18-23-21.821 [Notice] (Alsa) No chunk received for 5000ms. Closing ALSA. Mar 04 18:23:26 volumiobadet systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. Mar 04 18:23:26 volumiobadet systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 18:23:26 volumiobadet systemd[1]: qobuz-connect.service: Consumed 1.150s CPU time. Mar 04 18:23:26 volumiobadet systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 18:23:26 volumiobadet volumio[1106]: info: Executing endpoint qc_getconfig Mar 04 18:23:26 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.305 [2548.2548] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 04 18:23:26 volumiobadet volumio[1106]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 04 18:23:26 volumiobadet volumio[1106]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.311 [2548.2548] INFO VolumeManager: [0xd3a228]: Setting new playback volume: 75 Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.311 [2548.2548] INFO VolumeManager: [0xd3a228]: Setting new mute state: 0 Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.311 [2548.2548] INFO QobuzConnect: [0xd3abf8]: Client initialized! Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.311 [2548.2548] INFO SampleApp: Starting Avahi advertising, name: Volumiobadet, service name: _qobuz-connect._tcp Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.331 [2548.2548] INFO LocalConfigManager: [0xd39b08]: Starting Local Configuration server Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.331 [2548.2548] INFO SampleApp: Starting Local configuration server Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.332 [2548.2548] INFO SampleApp: Connected to UNIX socket client 0xd24818 Mar 04 18:23:26 volumiobadet qobuz-connect[2548]: 20260304 18:23:26.455 [2548.2548] INFO SampleApp: Playback volume changed: 75 Mar 04 18:23:26 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:26 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:26 volumiobadet wpa_supplicant[1066]: RRM: Ignoring radio measurement request: Not RRM network Mar 04 18:23:28 volumiobadet qobuz-connect[2548]: 20260304 18:23:28.326 [2548.2552] INFO SampleApp: API endpoint invoked: get-display-info Mar 04 18:23:28 volumiobadet qobuz-connect[2548]: 20260304 18:23:28.350 [2548.2552] INFO SampleApp: API endpoint invoked: get-connect-info Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.629 [2548.2552] INFO SampleApp: API endpoint invoked: connect-to-qconnect Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.629 [2548.2548] INFO EndpointManager: [0xd39738]: Updating API endpoint Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.629 [2548.2548] INFO EndpointManager: [0xd39738]: Updating QConnect endpoint Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.629 [2548.2548] INFO ActiveStateManager: [0xd38750]: Setting new active state: active Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.629 [2548.2548] INFO PlaybackSessionManager: [0xd39b28]: Starting playback session maintenance Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.629 [2548.2548] INFO HttpDownloader: [0xd39d18]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.629 [2548.2548] INFO CloudClient: [0xd3a398]: Connecting to the cloud Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.630 [2548.2548] INFO SampleApp: Renderer is now active Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.878 [2548.2548] INFO PlaybackSessionManager: [0xd39b28]: Playback session has been refreshed Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.929 [2548.2548] INFO CloudClient: [0xd3a398]: Connection established Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.929 [2548.2548] INFO QwspMessageSender: [0xe74ec0]: Sending Authenticate message Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.929 [2548.2548] INFO QwspMessageSender: [0xe74ec0]: Sending Subscribe message Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.930 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending JoinSession message Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.930 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending VolumeChanged message Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.930 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending VolumeMuted message Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.930 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending MaxAudioQualityChanged message Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.930 [2548.2548] INFO QwspMessageSender: [0xe74ec0]: Sending Payload message Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.986 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Received SetActive message: active Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.986 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Received SetState message: Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.986 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Playing state: Playing Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.986 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Playback position: 2742 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.986 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Queue version: 35.4 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.986 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Current track: TID: 381230934, QID: 0, Context UUID: c5e8bc56-7c63-43e8-b348-fe0105745834 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Next track: TID: 25427604, QID: 1, Context UUID: c5e8bc56-7c63-43e8-b348-fe0105745834 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO MediaEngine: [0xd39dc0]: Stopping playback, clearing tracks Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO MediaEngine: [0xd39dc0]: Initiating playback Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO RendererActionAvailabilityManager: [0xd3a450]: Renderer action 'Next' is available Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Received SetLoopMode message: Off Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO PlaybackControlsManager: [0xd3a198]: Setting new loop mode: Off Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO MediaEngine: [0xd39dc0]: Setting current track: 381230934, initial offset: 2742ms Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Clearing all streams Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: New stream: 1 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO HttpDownloader: [0xd40308]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=c46afa336fa65e9181db8184a7c17880&request_ts=1772645010&track_id=381230934 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO HttpDownloader: [0xd405c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=381230934 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO HttpDownloader: [0xd40790]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=9ba8686221c2cfcc44af5ae20f7a5057&request_ts=1772645010&track_id=381230934 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: [Stream 1]: Running audio stream Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Received SetShuffleMode message: disabled Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.987 [2548.2548] INFO PlaybackControlsManager: [0xd3a198]: Setting new shuffle mode: disabled Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.989 [2548.2548] INFO MediaEngine: [0xd39dc0]: Setting next track: 25427604 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.989 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: New stream: 2 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.989 [2548.2548] INFO HttpDownloader: [0xe2fae0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=a6348308fb2354d80c66d7ea3a68060a&request_ts=1772645010&track_id=25427604 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.989 [2548.2548] INFO HttpDownloader: [0xe3a5c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25427604 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.989 [2548.2548] INFO HttpDownloader: [0xe31710]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=8e4bc0b9426c3861d93570c8ee9c5434&request_ts=1772645010&track_id=25427604 Mar 04 18:23:30 volumiobadet qobuz-connect[2548]: 20260304 18:23:30.990 [2548.2548] INFO MediaEngine: [0xd39dc0]: Waiting for current stream to start before starting audio renderer Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: [Stream 1]: Metadata became available: Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Title: Silver Rain Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Artist: Marcus Miller Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Album: 3 Original Album Classics Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Album art URL: https://static.qobuz.com/images/covers/w1/5l/yb1r63mce5lw1_600.jpg Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: [Stream 2]: Metadata became available: Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Title: Elephants On Ice Skates Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Artist: Brian Bromberg Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Album: It Is What It Is Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.059 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: Album art URL: https://static.qobuz.com/images/covers/27/19/0181475701927_600.jpg Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.086 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=381230934&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648611&hmac=04JgHL0nVc-tByz78mb80FwCTQI Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.102 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=25427604&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648611&hmac=_Nkk1UgZMm3TsOwA3EtY6Xbovyg Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.140 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: [Stream 1]: stream information have been fetched Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.140 [2548.2548] INFO UrlAudioSource: [0xe2f3e0]: Starting URL audio source, initial position: 2742ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=381230934&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648611&hmac=04JgHL0nVc-tByz78mb80FwCTQI Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.140 [2548.2548] INFO ContentFetcher: [0xef6e28]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=10683778&eid=381230934&fmt=6&profile=raw&app_id=174516466&cid=3902093&etsp=1772648611&hmac=04JgHL0nVc-tByz78mb80FwCTQI, offset: 0 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.140 [2548.2548] INFO AudioRenderer: [0xd39fe0]: Starting audio renderer, initial playback state: Playing Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.140 [2548.2548] INFO SampleApp: [Stream 1]: New audio stream (starting from 2741ms) Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.141 [2548.2548] INFO SampleApp: [Stream 1]: Stream metadata became available: Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.141 [2548.2548] INFO SampleApp: Title: Silver Rain Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.141 [2548.2548] INFO SampleApp: Artist: Marcus Miller Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.141 [2548.2548] INFO SampleApp: Album: 3 Original Album Classics Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.141 [2548.2548] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/w1/5l/yb1r63mce5lw1_600.jpg Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: QobuzConnect: setActiveState() Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: QobuzConnect: Set Qobuz connect default volume to 18 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.144 [2548.2548] INFO VolumeManager: [0xd3a228]: Setting new playback volume: 18 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: QobuzConnect: Stopping currently active service Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioStop Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::stop Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::stPlaybackTimer Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::updateTrackBlock Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrackBlock Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.152 [2548.2548] INFO AudioStreamManager: [0xd39ea8]: [Stream 2]: stream information have been fetched Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::serviceStop Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: ControllerMpd::stop Mar 04 18:23:31 volumiobadet volumio[1106]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 18:23:31 volumiobadet volumio[1106]: info: sendMpdCommand stop took 15 milliseconds Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"Every Praise ((album edit))","artist":"Hezekiah Walker","album":"Every Praise ((album edit))","albumart":"https://static.qobuz.com/images/covers/25/97/0886443999725_600.jpg","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":1241,"duration":442,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"stream":false,"repeatMode":"all"} Mar 04 18:23:31 volumiobadet volumio[1106]: verbose: CURRENT POSITION 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::syncState stateService stop Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::syncState currentStatus stop Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: No code Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Mar 04 18:23:31 volumiobadet volumio[1106]: info: QobuzConnect: setActiveState() completed, send playback confirmation Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.207 [2548.2548] INFO SampleApp: Initiating playback, initial state: Playing Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: ALSA lib ./src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioMultiRoom has buffer size 120422 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: ALSA lib ./src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioMultiRoom has buffer size 120422 Mar 04 18:23:31 volumiobadet snapclient[2295]: 2026-03-04 18-23-31.231 [Info] (Alsa) PCM name: volumioMultiRoomClient, sample rate: 48000 Hz, channels: 2, buffer time: 80000 us, periods: 4, period time: 20000 us, period frames: 960 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.337 [2548.2548] INFO SampleApp: Playback state changed: Playing Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.337 [2548.2548] INFO StreamingEventReporter: [0xd3a918]: Reporting streaming started event Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.338 [2548.2548] INFO HttpDownloader: [0xd3a970]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.338 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Need to send state update Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.338 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending StateUpdated message Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.338 [2548.2548] INFO QwspMessageSender: [0xe74ec0]: Sending Payload message Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.339 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending FileAudioQualityChanged message Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.339 [2548.2548] INFO QwspMessageSender: [0xe74ec0]: Sending Payload message Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.339 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending DeviceAudioQualityChanged message Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.339 [2548.2548] INFO QwspMessageSender: [0xe74ec0]: Sending Payload message Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.339 [2548.2548] INFO SampleApp: Playback volume changed: 18 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.340 [2548.2548] INFO ProtocolHandler: [0xd3a500]: Volume changed: 18 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.340 [2548.2548] INFO QConnectMessageSender: [0xd6a9a8]: Sending VolumeChanged message Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.340 [2548.2548] INFO QwspMessageSender: [0xe74ec0]: Sending Payload message Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:31 volumiobadet volumio[1106]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"Every Praise ((album edit))","artist":"Hezekiah Walker","album":"Every Praise ((album edit))","albumart":"https://static.qobuz.com/images/covers/25/97/0886443999725_600.jpg","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":2740,"duration":442,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"stream":false,"repeatMode":"all"} Mar 04 18:23:31 volumiobadet volumio[1106]: verbose: CURRENT POSITION 0 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::syncState stateService stop Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::syncState currentStatus stop Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: No code Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.359 [2548.2548] INFO UrlAudioSource: [0xe2f3e0]: Content info extracted Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.359 [2548.2548] INFO UrlAudioSource: [0xe2f3e0]: Size: 41135679 Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.361 [2548.2559] INFO AudioDecoder: [0xf24fc0]: Stream info: Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.362 [2548.2559] INFO AudioDecoder: [0xf24fc0]: Total samples: 16145892 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.362 [2548.2559] INFO AudioDecoder: [0xf24fc0]: Sample rate: 44100 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.362 [2548.2559] INFO AudioDecoder: [0xf24fc0]: Channel count: 2 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.362 [2548.2559] INFO AudioDecoder: [0xf24fc0]: Bits per sample: 16 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.362 [2548.2559] INFO AudioDecoder: [0xf24fc0]: Duration: 366120 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.364 [2548.2548] INFO UrlAudioSource: [0xe2f3e0]: Audio properties have changed Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.364 [2548.2548] INFO UrlAudioSource: [0xe2f3e0]: Seeking input stream to byte offset: 245180 Mar 04 18:23:31 volumiobadet qobuz-connect[2548]: 20260304 18:23:31.364 [2548.2548] INFO ContentFetcher: [0xef6e28]: Adjusting download offset to: 245180 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::servicePushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:31 volumiobadet volumio[1106]: info: QobuzConnect: Volume received from daemon: 18 Mar 04 18:23:31 volumiobadet volumio[1106]: info: VolumeController::SetAlsaVolume18 Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreStateMachine::pushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output update for this device Mar 04 18:23:31 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:31 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:32 volumiobadet systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Mar 04 18:23:32 volumiobadet volumio[1106]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 04 18:23:32 volumiobadet volumio[1106]: info: QobuzConnect: setDeactiveState invoked Mar 04 18:23:32 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:32 volumiobadet volumio[1106]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Mar 04 18:23:32 volumiobadet volumio[1106]: verbose: UNSET VOLATILE: Service: qobuzconnect Mar 04 18:23:32 volumiobadet systemd[1]: qobuz-connect.service: Failed with result 'signal'. Mar 04 18:23:32 volumiobadet volumio[1106]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Mar 04 18:23:32 volumiobadet volumio[1106]: info: QobuzConnect: Received stop Mar 04 18:23:32 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:33 volumiobadet volumio[1106]: info: Executing endpoint metavolumio Mar 04 18:23:33 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 18:23:33 volumiobadet volumio[1106]: info: Executing endpoint metavolumio Mar 04 18:23:33 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 18:23:33 volumiobadet volumio[1106]: info: Executing endpoint metavolumio Mar 04 18:23:33 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 18:23:34 volumiobadet snapclient[2295]: 2026-03-04 18-23-34.061 [Info] (Stream) Exception: Not enough frames available, requested frames: 960, available: 327 Mar 04 18:23:34 volumiobadet snapclient[2295]: 2026-03-04 18-23-34.061 [Info] (Alsa) Failed to get chunk Mar 04 18:23:37 volumiobadet systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 5. Mar 04 18:23:37 volumiobadet systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 18:23:37 volumiobadet systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 18:23:38 volumiobadet volumio[1106]: info: Executing endpoint qc_getconfig Mar 04 18:23:38 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.056 [2575.2575] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 04 18:23:38 volumiobadet volumio[1106]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 04 18:23:38 volumiobadet volumio[1106]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.062 [2575.2575] INFO VolumeManager: [0x1f83228]: Setting new playback volume: 75 Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.062 [2575.2575] INFO VolumeManager: [0x1f83228]: Setting new mute state: 0 Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.062 [2575.2575] INFO QobuzConnect: [0x1f83bf8]: Client initialized! Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.062 [2575.2575] INFO SampleApp: Starting Avahi advertising, name: Volumiobadet, service name: _qobuz-connect._tcp Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.079 [2575.2575] INFO LocalConfigManager: [0x1f82b08]: Starting Local Configuration server Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.079 [2575.2575] INFO SampleApp: Starting Local configuration server Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.079 [2575.2575] INFO SampleApp: Connected to UNIX socket client 0x1f6d818 Mar 04 18:23:38 volumiobadet qobuz-connect[2575]: 20260304 18:23:38.206 [2575.2575] INFO SampleApp: Playback volume changed: 75 Mar 04 18:23:38 volumiobadet volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 04 18:23:38 volumiobadet volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 04 18:23:39 volumiobadet snapclient[2295]: 2026-03-04 18-23-39.066 [Notice] (Alsa) No chunk received for 5000ms. Closing ALSA. Mar 04 18:23:45 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Mar 04 18:23:45 volumiobadet volumio[1106]: info: MRS: Setting this device as multiroomSync server Mar 04 18:23:45 volumiobadet volumio[1106]: info: MRS: Setting another device as multiroomSync client Mar 04 18:23:45 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:45 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:48 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:48 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:48 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:48 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:50 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Mar 04 18:23:50 volumiobadet volumio[1106]: info: MRS: Setting this device as multiroomSync server Mar 04 18:23:50 volumiobadet volumio[1106]: info: MRS: Setting another device as multiroomSync client Mar 04 18:23:50 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:50 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:50 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:52 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Removing multiroom client: 8a8117c1-9632-40f0-9c17-6c79368a84c4 Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Removing another device as multiroomSync client Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Check for remaining clients: 0 Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Setting this device as single Mar 04 18:23:52 volumiobadet volumio[1106]: info: Mar 04 18:23:52 volumiobadet volumio[1106]: [1772645032043] ---------------------------- Setting Multiroom Single Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: disable multiroom output Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Set multiroom target PCM to volumioDiscard Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:52 volumiobadet volumio[1106]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:52 volumiobadet volumio[1106]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Mar 04 18:23:52 volumiobadet volumio[1106]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Mar 04 18:23:53 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Setting this device as multiroomSync server Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Setting another device as multiroomSync client Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Setting this device as Server Mar 04 18:23:53 volumiobadet volumio[1106]: info: Mar 04 18:23:53 volumiobadet volumio[1106]: [1772645033165] ---------------------------- MRS: Setting Multiroom Server Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:23:53 volumiobadet volumio[1106]: info: Enabled audio output: 8a8117c1-9632-40f0-9c17-6c79368a84c4 Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: STARTING SNAPCLIENT Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: STOPPING SNAPCLIENT Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:53 volumiobadet sudo[2600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapclient Mar 04 18:23:53 volumiobadet sudo[2600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:53 volumiobadet snapclient[2295]: 2026-03-04 18-23-53.293 [Info] (Snapclient) Received signal 15: Terminated Mar 04 18:23:53 volumiobadet systemd[1]: Stopping volumioSnapclient.service - Snapcast client... Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:53 volumiobadet snapserver[2301]: Error reading message header of length 0: End of file Mar 04 18:23:53 volumiobadet snapserver[2301]: onDisconnect: b8:27:eb:00:3e:2a Mar 04 18:23:53 volumiobadet snapclient[2295]: 2026-03-04 18-23-53.382 [Notice] (Snapclient) Snapclient terminated. Mar 04 18:23:53 volumiobadet systemd[1]: volumioSnapclient.service: Deactivated successfully. Mar 04 18:23:53 volumiobadet systemd[1]: Stopped volumioSnapclient.service - Snapcast client. Mar 04 18:23:53 volumiobadet systemd[1]: Started volumioSnapclient.service - Snapcast client. Mar 04 18:23:53 volumiobadet sudo[2600]: pam_unix(sudo:session): session closed for user root Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: SNAPCLIENT STARTED Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: STOPPING SNAPCLIENT Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.512 [Info] (Snapclient) Version 0.26.0 Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Snap server stop Mar 04 18:23:53 volumiobadet sudo[2605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.542 [Info] (Avahi) (Browser) NEW: service 'Snapcast' of type '_snapcast._tcp' in domain 'local' Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.547 [Info] (Avahi) (Browser) NEW: service 'Snapcast' of type '_snapcast._tcp' in domain 'local' Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.551 [Info] (Avahi) (Browser) CACHE_EXHAUSTED Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.551 [Info] (Avahi) Service 'Snapcast' of type '_snapcast._tcp' in domain 'local': Mar 04 18:23:53 volumiobadet sudo[2605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.551 [Info] (Avahi) volumiobadet.local:1704 (192.168.10.199) Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.557 [Info] (Controller) Found server 192.168.10.199:1704 Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.557 [Info] (Connection) Resolving host IP for: 192.168.10.199 Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.557 [Info] (Connection) Connecting Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.557 [Notice] (Connection) Connected to 192.168.10.199 Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.558 [Info] (Connection) My MAC: "b8:27:eb:00:3e:2a", socket: 8 Mar 04 18:23:53 volumiobadet snapserver[2301]: StreamServer::NewConnection: 192.168.10.199 Mar 04 18:23:53 volumiobadet sudo[2607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 04 18:23:53 volumiobadet sudo[2607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: STARTING SNAPSERVER Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: enable multiroom server output Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Set multiroom target PCM to volumioMultiRoom Mar 04 18:23:53 volumiobadet systemd[1]: Stopping volumioSnapclient.service - Snapcast client... Mar 04 18:23:53 volumiobadet volumio[1106]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.612 [Info] (Snapclient) Received signal 15: Terminated Mar 04 18:23:53 volumiobadet snapclient[2602]: 2026-03-04 18-23-53.613 [Notice] (Snapclient) Snapclient terminated. Mar 04 18:23:53 volumiobadet snapserver[2301]: Hello from b8:27:eb:00:3e:2a, host: volumiobadet, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Mar 04 18:23:53 volumiobadet snapserver[2301]: Error reading message header of length 0: End of file Mar 04 18:23:53 volumiobadet snapserver[2301]: onDisconnect: b8:27:eb:00:3e:2a Mar 04 18:23:53 volumiobadet snapserver[2301]: StreamSession write error (msg length: 0): Broken pipe Mar 04 18:23:53 volumiobadet snapserver[2301]: Error in socket shutdown: Transport endpoint is not connected Mar 04 18:23:53 volumiobadet systemd[1]: volumioSnapclient.service: Deactivated successfully. Mar 04 18:23:53 volumiobadet systemd[1]: Stopped volumioSnapclient.service - Snapcast client. Mar 04 18:23:53 volumiobadet volumio[1106]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: Snap server restart Mar 04 18:23:53 volumiobadet sudo[2630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapserver Mar 04 18:23:53 volumiobadet sudo[2630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:23:53 volumiobadet sudo[2605]: pam_unix(sudo:session): session closed for user root Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: SNAPCLIENT STOPPED Mar 04 18:23:53 volumiobadet snapserver[2301]: Received signal 15: Terminated Mar 04 18:23:53 volumiobadet snapserver[2301]: Stopping streamServer Mar 04 18:23:53 volumiobadet systemd[1]: Stopping volumioSnapserver.service - Snapcast server... Mar 04 18:23:53 volumiobadet snapserver[2301]: done Mar 04 18:23:53 volumiobadet snapserver[2301]: Snapserver terminated. Mar 04 18:23:53 volumiobadet systemd[1]: volumioSnapserver.service: Deactivated successfully. Mar 04 18:23:53 volumiobadet systemd[1]: Stopped volumioSnapserver.service - Snapcast server. Mar 04 18:23:53 volumiobadet sudo[2607]: pam_unix(sudo:session): session closed for user root Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: SNAPSERVER STOPPED Mar 04 18:23:53 volumiobadet systemd[1]: Started volumioSnapserver.service - Snapcast server. Mar 04 18:23:53 volumiobadet sudo[2630]: pam_unix(sudo:session): session closed for user root Mar 04 18:23:53 volumiobadet volumio[1106]: info: MRS: SNAPSERVER STARTED Mar 04 18:23:53 volumiobadet snapserver[2633]: Version 0.26.0 Mar 04 18:23:53 volumiobadet snapserver[2633]: Adding source: pipe:///tmp/multiroom/server/fifo?name=Radio&sampleformat=48000:16:2&codec=flac Mar 04 18:23:53 volumiobadet snapserver[2633]: Settings file: "/var/lib/snapserver/.config/snapserver/server.json" Mar 04 18:23:53 volumiobadet snapserver[2633]: Using HTTP host name: volumiobadet Mar 04 18:23:53 volumiobadet snapserver[2633]: Adding service 'Snapcast' Mar 04 18:23:53 volumiobadet snapserver[2633]: PcmStream: Radio, sampleFormat: 48000:16:2 Mar 04 18:23:53 volumiobadet snapserver[2633]: PipeStream mode: create Mar 04 18:23:53 volumiobadet snapserver[2633]: Stream: {"fragment":"","host":"","path":"/tmp/multiroom/server/fifo","query":{"chunk_ms":"50","codec":"flac","name":"Radio","sampleformat":"48000:16:2"},"raw":"pipe:////tmp/multiroom/server/fifo?chunk_ms=50&codec=flac&name=Radio&sampleformat=48000:16:2","scheme":"pipe"} Mar 04 18:23:53 volumiobadet snapserver[2633]: Init - compression level: 2 Mar 04 18:23:53 volumiobadet snapserver[2633]: Exception: end of file Mar 04 18:23:53 volumiobadet snapserver[2633]: Creating TCP acceptor for address: 0.0.0.0, port: 1705 Mar 04 18:23:53 volumiobadet snapserver[2633]: Creating HTTP acceptor for address: 0.0.0.0, port: 1780 Mar 04 18:23:53 volumiobadet snapserver[2633]: Creating stream acceptor for address: 0.0.0.0, port: 1704 Mar 04 18:23:53 volumiobadet snapserver[2633]: Number of threads: 4, hw threads: 4 Mar 04 18:23:54 volumiobadet snapserver[2633]: Service 'Snapcast' successfully established. Mar 04 18:23:55 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:55 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:55 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:55 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:55 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:23:56 volumiobadet snapserver[2633]: StreamServer::NewConnection: 192.168.10.160 Mar 04 18:23:56 volumiobadet snapserver[2633]: Hello from b8:27:eb:0c:ae:fa, host: volumiokontor, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Mar 04 18:23:57 volumiobadet snapserver[2633]: Error reading message header of length 0: End of file Mar 04 18:23:57 volumiobadet snapserver[2633]: onDisconnect: b8:27:eb:0c:ae:fa Mar 04 18:24:09 volumiobadet volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Starting cast device: Google-Nest-Mini-4d79aaf573682caa95f9259c1eea02b9 Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Removing 8a8117c1-9632-40f0-9c17-6c79368a84c4 as a multiroom client Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Removing another device as multiroomSync client Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Pushing multiroomSync output Mar 04 18:24:09 volumiobadet volumio[1106]: info: Enabled audio output: Google-Nest-Mini-4d79aaf573682caa95f9259c1eea02b9 Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Setting this device as Streaming Server Mar 04 18:24:09 volumiobadet volumio[1106]: info: Mar 04 18:24:09 volumiobadet volumio[1106]: [1772645049827] ---------------------------- MRS: Setting Streaming Server Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Snap server stop Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: enable multiroom server output Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Set multiroom target PCM to volumioMultiRoom Mar 04 18:24:09 volumiobadet sudo[2669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 04 18:24:09 volumiobadet sudo[2669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Updating multiroomSync output Mar 04 18:24:09 volumiobadet snapserver[2633]: Received signal 15: Terminated Mar 04 18:24:09 volumiobadet systemd[1]: Stopping volumioSnapserver.service - Snapcast server... Mar 04 18:24:09 volumiobadet snapserver[2633]: Stopping streamServer Mar 04 18:24:09 volumiobadet snapserver[2633]: done Mar 04 18:24:09 volumiobadet snapserver[2633]: Snapserver terminated. Mar 04 18:24:09 volumiobadet volumio[1106]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Mar 04 18:24:09 volumiobadet systemd[1]: volumioSnapserver.service: Deactivated successfully. Mar 04 18:24:09 volumiobadet systemd[1]: Stopped volumioSnapserver.service - Snapcast server. Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Mar 04 18:24:09 volumiobadet sudo[2669]: pam_unix(sudo:session): session closed for user root Mar 04 18:24:09 volumiobadet volumio[1106]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: SNAPSERVER STOPPED Mar 04 18:24:09 volumiobadet volumio[1106]: info: MRS: STARTING volumioStreaming Mar 04 18:24:10 volumiobadet sudo[2674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 04 18:24:10 volumiobadet sudo[2674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:24:10 volumiobadet sudo[2676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Mar 04 18:24:10 volumiobadet sudo[2676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:24:10 volumiobadet sudo[2674]: pam_unix(sudo:session): session closed for user root Mar 04 18:24:10 volumiobadet systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Mar 04 18:24:10 volumiobadet sudo[2676]: pam_unix(sudo:session): session closed for user root Mar 04 18:24:10 volumiobadet volumio[1106]: info: MRS: volumioStreaming STARTED Mar 04 18:24:10 volumiobadet sudo[2680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 04 18:24:10 volumiobadet sudo[2680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 18:24:10 volumiobadet sudo[2680]: pam_unix(sudo:session): session closed for user root Mar 04 18:24:10 volumiobadet volumio[1106]: info: MRS: Connected to cast device: Google-Nest-Mini-4d79aaf573682caa95f9259c1eea02b9 Mar 04 18:24:10 volumiobadet volumio[1106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 18:24:10 volumiobadet volumio[1106]: TypeError: Cannot read properties of undefined (reading 'on') Mar 04 18:24:10 volumiobadet volumio[1106]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29945 Mar 04 18:24:10 volumiobadet volumio[1106]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Mar 04 18:24:10 volumiobadet volumio[1106]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Mar 04 18:24:10 volumiobadet volumio[1106]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Mar 04 18:24:10 volumiobadet volumio[1106]: at fn.emit (node:events:526:35) Mar 04 18:24:10 volumiobadet volumio[1106]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Mar 04 18:24:10 volumiobadet volumio[1106]: at Channel.emit (node:events:514:28) Mar 04 18:24:10 volumiobadet volumio[1106]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Mar 04 18:24:10 volumiobadet volumio[1106]: at Client.emit (node:events:526:35) Mar 04 18:24:10 volumiobadet volumio[1106]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Mar 04 18:24:10 volumiobadet volumio[1106]: at PacketStreamWrapper.emit (node:events:514:28) Mar 04 18:24:10 volumiobadet volumio[1106]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Mar 04 18:24:10 volumiobadet volumio[1106]: at TLSSocket.emit (node:events:514:28) Mar 04 18:24:10 volumiobadet volumio[1106]: at emitReadable_ (node:internal/streams/readable:609:12) Mar 04 18:24:10 volumiobadet volumio[1106]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) Mar 04 18:24:10 volumiobadet volumio[1106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 18:24:11 volumiobadet sudo[2697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 18:23' Mar 04 18:24:11 volumiobadet sudo[2697]: 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"