Oct 02 09:24:04 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 128. Oct 02 09:24:04 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:04 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:04 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:04 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:04 volumio qobuz-connect[6504]: 20261002 09:24:04.592 [6504.6504] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:04 volumio qobuz-connect[6504]: 20261002 09:24:04.600 [6504.6504] INFO VolumeManager: [0x1b95138]: Setting new playback volume: 75 Oct 02 09:24:04 volumio qobuz-connect[6504]: 20261002 09:24:04.600 [6504.6504] INFO VolumeManager: [0x1b95138]: Setting new mute state: 0 Oct 02 09:24:04 volumio qobuz-connect[6504]: 20261002 09:24:04.601 [6504.6504] INFO AudioStreamManager: [0x1b94e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:04 volumio qobuz-connect[6504]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:04 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:04 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:06 volumio volumio[1271]: info: Preload queue cleared Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::ClearQueue Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::stop Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::serviceStop Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::serviceStop Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::clearPlayQueue Oct 02 09:24:06 volumio vtcs[4699]: [close:85] Entering Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::saveQueue Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioPushQueue Oct 02 09:24:06 volumio vtcs[4699]: [feedThread:276] Exiting Oct 02 09:24:06 volumio vtcs[4699]: [close:100] Exiting Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::addQueueItems Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::addQueueItems Oct 02 09:24:06 volumio volumio[1271]: info: Preload queue cleared Oct 02 09:24:06 volumio volumio[1271]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioPushQueue Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::saveQueue Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::updateTrackBlock Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::getTrackBlock Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioPlay Oct 02 09:24:06 volumio volumio[1271]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 02 09:24:06 volumio volumio[1271]: info: unSetVolatile called Oct 02 09:24:06 volumio volumio[1271]: info: Relinquishing Volumio State to another service Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::play index 0 Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::stop Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::stPlaybackTimer Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::updateTrackBlock Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::getTrackBlock Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:06 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:06 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:06 volumio vtcs[4699]: [2026-10-02 09:24:06.635] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::serviceStop Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::serviceStop Oct 02 09:24:06 volumio volumio[1271]: info: [1790925846637] ControllerWebradio::stop Oct 02 09:24:06 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 09:24:06 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:06.641Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 09:24:06 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:06.642Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Oct 02 09:24:06 volumio volumio[1271]: info: PlaybackInactive Oct 02 09:24:06 volumio volumio[1271]: info: Device Session is_active: true Oct 02 09:24:06 volumio volumio[1271]: info: DeviceInactive Oct 02 09:24:06 volumio volumio[1271]: info: Relinquishing Volumio State Oct 02 09:24:06 volumio volumio[1271]: info: sendMpdCommand stop took 25 milliseconds Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::play index undefined Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::startPlaybackTimer Oct 02 09:24:06 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 09:24:06 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 09:24:06 volumio volumio[1271]: info: [1790925846665] ControllerWebradio::clearAddPlayTrack Oct 02 09:24:06 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 09:24:06 volumio volumio[1271]: info: sendMpdCommand stop took 1 milliseconds Oct 02 09:24:06 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 09:24:06 volumio volumio[1271]: info: Oct 02 09:24:06 volumio volumio[1271]: ---------------------------- MPD announces system playlist update Oct 02 09:24:06 volumio volumio[1271]: info: Ignoring MPD Status Update Oct 02 09:24:06 volumio volumio[1271]: info: sendMpdCommand clear took 2 milliseconds Oct 02 09:24:06 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Oct 02 09:24:06 volumio volumio[1271]: error: updateQueue error: null Oct 02 09:24:06 volumio volumio[1271]: info: ------------------------------ 3ms Oct 02 09:24:06 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Oct 02 09:24:06 volumio volumio[1271]: info: Oct 02 09:24:06 volumio volumio[1271]: ---------------------------- MPD announces system playlist update Oct 02 09:24:06 volumio volumio[1271]: info: Ignoring MPD Status Update Oct 02 09:24:06 volumio volumio[1271]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 2 milliseconds Oct 02 09:24:06 volumio volumio[1271]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 09:24:06 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand play Oct 02 09:24:06 volumio volumio[1271]: info: ------------------------------ 3ms Oct 02 09:24:06 volumio volumio[1271]: info: sendMpdCommand play took 3 milliseconds Oct 02 09:24:07 volumio volumio[1271]: info: Oct 02 09:24:07 volumio volumio[1271]: ---------------------------- MPD announces state update: player Oct 02 09:24:07 volumio volumio[1271]: info: ControllerMpd::getState Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:24:07 volumio volumio[1271]: info: Oct 02 09:24:07 volumio volumio[1271]: ---------------------------- MPD announces state update: player Oct 02 09:24:07 volumio volumio[1271]: info: sendMpdCommand status took 29 milliseconds Oct 02 09:24:07 volumio volumio[1271]: info: ControllerMpd::getState Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::parseState Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:24:07 volumio volumio[1271]: info: Oct 02 09:24:07 volumio volumio[1271]: ---------------------------- MPD announces system playlist update Oct 02 09:24:07 volumio volumio[1271]: info: Ignoring MPD Status Update Oct 02 09:24:07 volumio volumio[1271]: info: Oct 02 09:24:07 volumio volumio[1271]: ---------------------------- MPD announces system playlist update Oct 02 09:24:07 volumio volumio[1271]: info: Ignoring MPD Status Update Oct 02 09:24:07 volumio volumio[1271]: info: Oct 02 09:24:07 volumio volumio[1271]: ---------------------------- MPD announces state update: player Oct 02 09:24:07 volumio volumio[1271]: info: sendMpdCommand status took 3 milliseconds Oct 02 09:24:07 volumio volumio[1271]: info: ControllerMpd::getState Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:24:07 volumio volumio[1271]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::parseState Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:24:07 volumio volumio[1271]: info: ControllerMpd::pushState Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:07 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:07 volumio volumio[1271]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Johnny Varro - Disc Jockey Jump","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 02 09:24:07 volumio volumio[1271]: verbose: CURRENT POSITION 0 Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::syncState stateService play Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Oct 02 09:24:07 volumio volumio[1271]: info: ------------------------------ 42ms Oct 02 09:24:07 volumio volumio[1271]: info: ------------------------------ 12ms Oct 02 09:24:07 volumio volumio[1271]: info: ------------------------------ 12ms Oct 02 09:24:07 volumio volumio[1271]: info: sendMpdCommand status took 10 milliseconds Oct 02 09:24:07 volumio volumio[1271]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::parseState Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:24:07 volumio volumio[1271]: info: ControllerMpd::pushState Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:07 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:07 volumio volumio[1271]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1230,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Johnny Varro - Disc Jockey Jump","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 02 09:24:07 volumio volumio[1271]: verbose: CURRENT POSITION 0 Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::syncState stateService play Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::syncState currentStatus play Oct 02 09:24:07 volumio volumio[1271]: info: Received an update from plugin. extracting info from payload Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.656Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.657Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Johnny Varro - Disc Jockey Jump" Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.658Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.659Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Johnny Varro - Disc Jockey Jump" Oct 02 09:24:07 volumio volumio[1271]: info: ------------------------------ 49ms Oct 02 09:24:07 volumio volumio[1271]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 02 09:24:07 volumio volumio[1271]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:24:07 volumio volumio[1271]: info: ControllerMpd::pushState Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:07 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:07 volumio volumio[1271]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1230,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Johnny Varro - Disc Jockey Jump","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 02 09:24:07 volumio volumio[1271]: verbose: CURRENT POSITION 0 Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::syncState stateService play Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::syncState currentStatus play Oct 02 09:24:07 volumio volumio[1271]: info: Received an update from plugin. extracting info from payload Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:07 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:07 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:07 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.679Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=244 volume=100 Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.680Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Johnny Varro - Disc Jockey Jump" Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.681Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=244 volume=100 Oct 02 09:24:07 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:07.682Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Johnny Varro - Disc Jockey Jump" Oct 02 09:24:07 volumio volumio[1271]: info: ------------------------------ 65ms Oct 02 09:24:09 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:24:09 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:24:09 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 129. Oct 02 09:24:09 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:09 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:24:09 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:24:09 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:09 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:09 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:09 volumio qobuz-connect[6515]: 20261002 09:24:09.821 [6515.6515] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:09 volumio qobuz-connect[6515]: 20261002 09:24:09.827 [6515.6515] INFO VolumeManager: [0x15d0138]: Setting new playback volume: 75 Oct 02 09:24:09 volumio qobuz-connect[6515]: 20261002 09:24:09.827 [6515.6515] INFO VolumeManager: [0x15d0138]: Setting new mute state: 0 Oct 02 09:24:09 volumio qobuz-connect[6515]: 20261002 09:24:09.827 [6515.6515] INFO AudioStreamManager: [0x15cfe90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:09 volumio qobuz-connect[6515]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:09 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:09 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:14 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 130. Oct 02 09:24:14 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:15 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:15 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:15 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:15 volumio qobuz-connect[6533]: 20261002 09:24:15.110 [6533.6533] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:15 volumio qobuz-connect[6533]: 20261002 09:24:15.117 [6533.6533] INFO VolumeManager: [0x1c31138]: Setting new playback volume: 75 Oct 02 09:24:15 volumio qobuz-connect[6533]: 20261002 09:24:15.118 [6533.6533] INFO VolumeManager: [0x1c31138]: Setting new mute state: 0 Oct 02 09:24:15 volumio qobuz-connect[6533]: 20261002 09:24:15.118 [6533.6533] INFO AudioStreamManager: [0x1c30e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:15 volumio qobuz-connect[6533]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:15 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:15 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:16 volumio vtcs[4699]: [2026-10-02 09:24:16] [error] handle_read_frame error: asio.system:125 (Operation aborted.) Oct 02 09:24:18 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 02 09:24:18 volumio volumio[1271]: info: Preload queue cleared Oct 02 09:24:20 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 131. Oct 02 09:24:20 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:20 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:20 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:20 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:20 volumio qobuz-connect[6538]: 20261002 09:24:20.341 [6538.6538] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:20 volumio qobuz-connect[6538]: 20261002 09:24:20.347 [6538.6538] INFO VolumeManager: [0x13c2138]: Setting new playback volume: 75 Oct 02 09:24:20 volumio qobuz-connect[6538]: 20261002 09:24:20.347 [6538.6538] INFO VolumeManager: [0x13c2138]: Setting new mute state: 0 Oct 02 09:24:20 volumio qobuz-connect[6538]: 20261002 09:24:20.347 [6538.6538] INFO AudioStreamManager: [0x13c1e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:20 volumio qobuz-connect[6538]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:20 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:20 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:25 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 132. Oct 02 09:24:25 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:25 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:25 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:25 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:25 volumio qobuz-connect[6556]: 20261002 09:24:25.590 [6556.6556] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:25 volumio qobuz-connect[6556]: 20261002 09:24:25.596 [6556.6556] INFO VolumeManager: [0x1ad8138]: Setting new playback volume: 75 Oct 02 09:24:25 volumio qobuz-connect[6556]: 20261002 09:24:25.596 [6556.6556] INFO VolumeManager: [0x1ad8138]: Setting new mute state: 0 Oct 02 09:24:25 volumio qobuz-connect[6556]: 20261002 09:24:25.596 [6556.6556] INFO AudioStreamManager: [0x1ad7e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:25 volumio qobuz-connect[6556]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:25 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:25 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:30 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 133. Oct 02 09:24:30 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:30 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:30 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:30 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:30 volumio qobuz-connect[6560]: 20261002 09:24:30.848 [6560.6560] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:30 volumio qobuz-connect[6560]: 20261002 09:24:30.855 [6560.6560] INFO VolumeManager: [0xad2138]: Setting new playback volume: 75 Oct 02 09:24:30 volumio qobuz-connect[6560]: 20261002 09:24:30.855 [6560.6560] INFO VolumeManager: [0xad2138]: Setting new mute state: 0 Oct 02 09:24:30 volumio qobuz-connect[6560]: 20261002 09:24:30.855 [6560.6560] INFO AudioStreamManager: [0xad1e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:30 volumio qobuz-connect[6560]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:30 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:30 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:35 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 134. Oct 02 09:24:35 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:36 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:36 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:36 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:36 volumio qobuz-connect[6578]: 20261002 09:24:36.079 [6578.6578] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:36 volumio qobuz-connect[6578]: 20261002 09:24:36.085 [6578.6578] INFO VolumeManager: [0xd68138]: Setting new playback volume: 75 Oct 02 09:24:36 volumio qobuz-connect[6578]: 20261002 09:24:36.085 [6578.6578] INFO VolumeManager: [0xd68138]: Setting new mute state: 0 Oct 02 09:24:36 volumio qobuz-connect[6578]: 20261002 09:24:36.085 [6578.6578] INFO AudioStreamManager: [0xd67e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:36 volumio qobuz-connect[6578]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:36 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:36 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:39 volumio volumio[1271]: info: Currently active: webradio Oct 02 09:24:39 volumio volumio[1271]: info: Stopping currently active service Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioStop Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::stop Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::stPlaybackTimer Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::updateTrackBlock Oct 02 09:24:39 volumio volumio[1271]: info: CorePlayQueue::getTrackBlock Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:39 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:39 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::serviceStop Oct 02 09:24:39 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::serviceStop Oct 02 09:24:39 volumio volumio[1271]: info: [1790925879598] ControllerWebradio::stop Oct 02 09:24:39 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.601Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.602Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Oct 02 09:24:39 volumio volumio[1271]: info: Oct 02 09:24:39 volumio volumio[1271]: ---------------------------- MPD announces state update: player Oct 02 09:24:39 volumio volumio[1271]: info: sendMpdCommand stop took 24 milliseconds Oct 02 09:24:39 volumio volumio[1271]: info: ControllerMpd::getState Oct 02 09:24:39 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Oct 02 09:24:39 volumio volumio[1271]: info: TidalConnect Active Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:39 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:39 volumio volumio[1271]: info: Currently active: webradio Oct 02 09:24:39 volumio volumio[1271]: info: Setting Volatile state to tidalconnect Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.637Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.638Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.638Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.639Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:39 volumio volumio[1271]: info: sendMpdCommand status took 20 milliseconds Oct 02 09:24:39 volumio volumio[1271]: verbose: ControllerMpd::parseState Oct 02 09:24:39 volumio volumio[1271]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 09:24:39 volumio volumio[1271]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 02 09:24:39 volumio volumio[1271]: verbose: ControllerMpd::parseTrackInfo Oct 02 09:24:39 volumio volumio[1271]: info: ControllerMpd::pushState Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:39 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:39 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:39 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:39 volumio volumio[1271]: info: CorePlayQueue::getTrack 0 Oct 02 09:24:39 volumio volumio[1271]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.669Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:39 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:39.671Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:39 volumio volumio[1271]: info: ------------------------------ 49ms Oct 02 09:24:40 volumio vtcs[4699]: [2026-10-02 09:24:40.013] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:1 Oct 02 09:24:40 volumio volumio[1271]: info: Pushing metadata Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:40 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:40 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:40 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:40 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:40 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:40 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:40 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:40 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:40 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:40 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:40.590Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:40.590Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:40.593Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:40.593Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:40.594Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:40.595Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:41 volumio vtcs[4699]: [close:85] Entering Oct 02 09:24:41 volumio vtcs[4699]: [close:100] Exiting Oct 02 09:24:41 volumio volumio[1271]: info: Pushing metadata Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:41 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:41 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:41 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:41.163Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:41.164Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:41 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 135. Oct 02 09:24:41 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:41 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:41 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:41 volumio qobuz-connect[6590]: 20261002 09:24:41.316 [6590.6590] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:41 volumio qobuz-connect[6590]: 20261002 09:24:41.322 [6590.6590] INFO VolumeManager: [0xb9c138]: Setting new playback volume: 75 Oct 02 09:24:41 volumio qobuz-connect[6590]: 20261002 09:24:41.322 [6590.6590] INFO VolumeManager: [0xb9c138]: Setting new mute state: 0 Oct 02 09:24:41 volumio qobuz-connect[6590]: 20261002 09:24:41.322 [6590.6590] INFO AudioStreamManager: [0xb9be90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:41 volumio qobuz-connect[6590]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:41 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:41 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:41 volumio vtcs[4699]: [close:85] Entering Oct 02 09:24:41 volumio vtcs[4699]: [close:100] Exiting Oct 02 09:24:41 volumio vtcs[4699]: [open:50] Opening with sample rate: 44100, type: 0 Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:41 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:41 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:41 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:41.649Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:41.650Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:41 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:41 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:41 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:41.665Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=0 volume=100 Oct 02 09:24:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:41.666Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:41 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:24:41 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:24:41 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:24:41 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:24:42 volumio vtcs[4699]: [start:106] Entering Oct 02 09:24:42 volumio vtcs[4699]: [start:113] Reopening PCM device... Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:42 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:42 volumio vtcs[4699]: [start:115] PCM opened successfully. Oct 02 09:24:42 volumio vtcs[4699]: [start:120] Exiting Oct 02 09:24:42 volumio vtcs[4699]: [feedThread:223] Entering Oct 02 09:24:42 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:42 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:42 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:42.162Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 09:24:42 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:42.163Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:24:42 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:24:42 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:24:42 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:24:42 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:24:42 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:42.578Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=549 volume=100 Oct 02 09:24:42 volumio volumio5-onboarding[1788]: time=2026-10-02T07:24:42.582Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nebulous Tango" Oct 02 09:24:46 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 136. Oct 02 09:24:46 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:46 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:46 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:46 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:46 volumio qobuz-connect[6610]: 20261002 09:24:46.608 [6610.6610] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:46 volumio qobuz-connect[6610]: 20261002 09:24:46.615 [6610.6610] INFO VolumeManager: [0xff5138]: Setting new playback volume: 75 Oct 02 09:24:46 volumio qobuz-connect[6610]: 20261002 09:24:46.615 [6610.6610] INFO VolumeManager: [0xff5138]: Setting new mute state: 0 Oct 02 09:24:46 volumio qobuz-connect[6610]: 20261002 09:24:46.615 [6610.6610] INFO AudioStreamManager: [0xff4e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:46 volumio qobuz-connect[6610]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:46 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:46 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:51 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 137. Oct 02 09:24:51 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:51 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:51 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:51 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:51 volumio qobuz-connect[6614]: 20261002 09:24:51.822 [6614.6614] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:51 volumio qobuz-connect[6614]: 20261002 09:24:51.828 [6614.6614] INFO VolumeManager: [0x58a138]: Setting new playback volume: 75 Oct 02 09:24:51 volumio qobuz-connect[6614]: 20261002 09:24:51.828 [6614.6614] INFO VolumeManager: [0x58a138]: Setting new mute state: 0 Oct 02 09:24:51 volumio qobuz-connect[6614]: 20261002 09:24:51.828 [6614.6614] INFO AudioStreamManager: [0x589e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:51 volumio qobuz-connect[6614]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:51 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:51 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:24:56 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 138. Oct 02 09:24:56 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:57 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:24:57 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:24:57 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:24:57 volumio qobuz-connect[6632]: 20261002 09:24:57.080 [6632.6632] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:24:57 volumio qobuz-connect[6632]: 20261002 09:24:57.086 [6632.6632] INFO VolumeManager: [0x653138]: Setting new playback volume: 75 Oct 02 09:24:57 volumio qobuz-connect[6632]: 20261002 09:24:57.086 [6632.6632] INFO VolumeManager: [0x653138]: Setting new mute state: 0 Oct 02 09:24:57 volumio qobuz-connect[6632]: 20261002 09:24:57.086 [6632.6632] INFO AudioStreamManager: [0x652e90]: Setting new audio download buffer size: 1048576 Oct 02 09:24:57 volumio qobuz-connect[6632]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:24:57 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:24:57 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:02 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 139. Oct 02 09:25:02 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:02 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:02 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:02 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:02 volumio qobuz-connect[6638]: 20261002 09:25:02.335 [6638.6638] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:02 volumio qobuz-connect[6638]: 20261002 09:25:02.342 [6638.6638] INFO VolumeManager: [0x2477138]: Setting new playback volume: 75 Oct 02 09:25:02 volumio qobuz-connect[6638]: 20261002 09:25:02.342 [6638.6638] INFO VolumeManager: [0x2477138]: Setting new mute state: 0 Oct 02 09:25:02 volumio qobuz-connect[6638]: 20261002 09:25:02.342 [6638.6638] INFO AudioStreamManager: [0x2476e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:02 volumio qobuz-connect[6638]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:02 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:02 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:07 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 140. Oct 02 09:25:07 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:07 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:07 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:07 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:07 volumio qobuz-connect[6656]: 20261002 09:25:07.600 [6656.6656] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:07 volumio qobuz-connect[6656]: 20261002 09:25:07.607 [6656.6656] INFO VolumeManager: [0x22c6138]: Setting new playback volume: 75 Oct 02 09:25:07 volumio qobuz-connect[6656]: 20261002 09:25:07.607 [6656.6656] INFO VolumeManager: [0x22c6138]: Setting new mute state: 0 Oct 02 09:25:07 volumio qobuz-connect[6656]: 20261002 09:25:07.607 [6656.6656] INFO AudioStreamManager: [0x22c5e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:07 volumio qobuz-connect[6656]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:07 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:07 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:08 volumio vtcs[4699]: [close:85] Entering Oct 02 09:25:08 volumio vtcs[4699]: [feedThread:276] Exiting Oct 02 09:25:08 volumio vtcs[4699]: [close:100] Exiting Oct 02 09:25:08 volumio volumio[1271]: info: Pushing metadata Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:25:08 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:25:08 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:25:08 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:25:08 volumio vtcs[4699]: [close:85] Entering Oct 02 09:25:08 volumio vtcs[4699]: [close:100] Exiting Oct 02 09:25:08 volumio vtcs[4699]: [open:50] Opening with sample rate: 88200, type: 3 Oct 02 09:25:08 volumio vtcs[4699]: [start:106] Entering Oct 02 09:25:08 volumio vtcs[4699]: [start:113] Reopening PCM device... Oct 02 09:25:08 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:08.195Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=66648 volume=100 Oct 02 09:25:08 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:08.196Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nancy Wilson" Oct 02 09:25:08 volumio vtcs[4699]: [start:115] PCM opened successfully. Oct 02 09:25:08 volumio vtcs[4699]: [start:120] Exiting Oct 02 09:25:08 volumio vtcs[4699]: [feedThread:223] Entering Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:25:08 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:25:08 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:25:08 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::servicePushState Oct 02 09:25:08 volumio volumio[1271]: info: CoreStateMachine::pushState Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::volumioPushState Oct 02 09:25:08 volumio volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Oct 02 09:25:08 volumio volumio[1271]: info: MRS: Pushing multiroomSync output Oct 02 09:25:08 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:25:08 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:08.216Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=66648 volume=100 Oct 02 09:25:08 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:08.217Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nancy Wilson" Oct 02 09:25:08 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:08.218Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 09:25:08 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:08.219Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" id= title="Nancy Wilson" Oct 02 09:25:10 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:25:10 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:25:10 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:25:10 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:25:10 volumio volumio[1271]: info: Executing endpoint metavolumio Oct 02 09:25:10 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 09:25:12 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 141. Oct 02 09:25:12 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:12 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:12 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:12 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:12 volumio qobuz-connect[6674]: 20261002 09:25:12.874 [6674.6674] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:12 volumio qobuz-connect[6674]: 20261002 09:25:12.882 [6674.6674] INFO VolumeManager: [0x1487138]: Setting new playback volume: 75 Oct 02 09:25:12 volumio qobuz-connect[6674]: 20261002 09:25:12.882 [6674.6674] INFO VolumeManager: [0x1487138]: Setting new mute state: 0 Oct 02 09:25:12 volumio qobuz-connect[6674]: 20261002 09:25:12.882 [6674.6674] INFO AudioStreamManager: [0x1486e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:12 volumio qobuz-connect[6674]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:12 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:12 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:17 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 142. Oct 02 09:25:17 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:17 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:18 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:18 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:18 volumio qobuz-connect[6681]: 20261002 09:25:18.071 [6681.6681] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:18 volumio qobuz-connect[6681]: 20261002 09:25:18.077 [6681.6681] INFO VolumeManager: [0xd68138]: Setting new playback volume: 75 Oct 02 09:25:18 volumio qobuz-connect[6681]: 20261002 09:25:18.077 [6681.6681] INFO VolumeManager: [0xd68138]: Setting new mute state: 0 Oct 02 09:25:18 volumio qobuz-connect[6681]: 20261002 09:25:18.077 [6681.6681] INFO AudioStreamManager: [0xd67e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:18 volumio qobuz-connect[6681]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:18 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:18 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:23 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 143. Oct 02 09:25:23 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:23 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:23 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:23 volumio qobuz-connect[6699]: 20261002 09:25:23.342 [6699.6699] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:23 volumio qobuz-connect[6699]: 20261002 09:25:23.348 [6699.6699] INFO VolumeManager: [0x1120138]: Setting new playback volume: 75 Oct 02 09:25:23 volumio qobuz-connect[6699]: 20261002 09:25:23.349 [6699.6699] INFO VolumeManager: [0x1120138]: Setting new mute state: 0 Oct 02 09:25:23 volumio qobuz-connect[6699]: 20261002 09:25:23.349 [6699.6699] INFO AudioStreamManager: [0x111fe90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:23 volumio qobuz-connect[6699]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:23 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:23 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:28 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 144. Oct 02 09:25:28 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:28 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:28 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:28 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:28 volumio qobuz-connect[6703]: 20261002 09:25:28.598 [6703.6703] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:28 volumio qobuz-connect[6703]: 20261002 09:25:28.605 [6703.6703] INFO VolumeManager: [0xdf3138]: Setting new playback volume: 75 Oct 02 09:25:28 volumio qobuz-connect[6703]: 20261002 09:25:28.605 [6703.6703] INFO VolumeManager: [0xdf3138]: Setting new mute state: 0 Oct 02 09:25:28 volumio qobuz-connect[6703]: 20261002 09:25:28.605 [6703.6703] INFO AudioStreamManager: [0xdf2e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:28 volumio qobuz-connect[6703]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:28 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:28 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:33 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 145. Oct 02 09:25:33 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:33 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:33 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:33 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:33 volumio qobuz-connect[6721]: 20261002 09:25:33.838 [6721.6721] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:33 volumio qobuz-connect[6721]: 20261002 09:25:33.845 [6721.6721] INFO VolumeManager: [0xac2138]: Setting new playback volume: 75 Oct 02 09:25:33 volumio qobuz-connect[6721]: 20261002 09:25:33.845 [6721.6721] INFO VolumeManager: [0xac2138]: Setting new mute state: 0 Oct 02 09:25:33 volumio qobuz-connect[6721]: 20261002 09:25:33.845 [6721.6721] INFO AudioStreamManager: [0xac1e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:33 volumio qobuz-connect[6721]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:33 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:33 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:38 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 146. Oct 02 09:25:38 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:38 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:39 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:39 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:39 volumio qobuz-connect[6726]: 20261002 09:25:39.069 [6726.6726] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:39 volumio qobuz-connect[6726]: 20261002 09:25:39.075 [6726.6726] INFO VolumeManager: [0x6be138]: Setting new playback volume: 75 Oct 02 09:25:39 volumio qobuz-connect[6726]: 20261002 09:25:39.075 [6726.6726] INFO VolumeManager: [0x6be138]: Setting new mute state: 0 Oct 02 09:25:39 volumio qobuz-connect[6726]: 20261002 09:25:39.075 [6726.6726] INFO AudioStreamManager: [0x6bde90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:39 volumio qobuz-connect[6726]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:39 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:39 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.251Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.441Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=http://pushupdates.volumio.org duration=180.773484ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.528Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=276.961356ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.703Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://securetoken.googleapis.com duration=448.98929ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.736Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://www.googleapis.com duration=478.802962ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.761Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=http://cddb.volumio.org duration=503.454929ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.829Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=573.979744ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.867Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=611.949592ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.880Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://google.com duration=623.380343ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.914Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=660.101132ms Oct 02 09:25:40 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:40.978Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=http://plugins.volumio.org duration=719.63259ms Oct 02 09:25:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:41.080Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://functions.volumio.cloud duration=822.837373ms Oct 02 09:25:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:41.080Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://functions.volumio.cloud duration=820.906492ms Oct 02 09:25:41 volumio volumio5-onboarding[1788]: time=2026-10-02T07:25:41.181Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.63:59906,00:00:00:00:00:00%02 @ 0x2000120" latency=20.023361ms timeout=10s endpoint=https://database.volumio.cloud duration=921.280761ms Oct 02 09:25:43 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 09:25:43 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 09:25:43 volumio volumio[1271]: info: Discovery: Getting this device information Oct 02 09:25:43 volumio volumio[1271]: info: CoreCommandRouter::volumioGetState Oct 02 09:25:43 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 09:25:43 volumio volumio[1271]: verbose: New Socket.io Connection to 192.168.1.29:3000 from 192.168.1.63 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 02 09:25:43 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 09:25:43 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 09:25:44 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 147. Oct 02 09:25:44 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:44 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:44 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:44 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:44 volumio qobuz-connect[6744]: 20261002 09:25:44.342 [6744.6744] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:44 volumio qobuz-connect[6744]: 20261002 09:25:44.348 [6744.6744] INFO VolumeManager: [0x1732138]: Setting new playback volume: 75 Oct 02 09:25:44 volumio qobuz-connect[6744]: 20261002 09:25:44.348 [6744.6744] INFO VolumeManager: [0x1732138]: Setting new mute state: 0 Oct 02 09:25:44 volumio qobuz-connect[6744]: 20261002 09:25:44.348 [6744.6744] INFO AudioStreamManager: [0x1731e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:44 volumio qobuz-connect[6744]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:44 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:44 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:49 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 148. Oct 02 09:25:49 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:49 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 09:25:49 volumio volumio[1271]: info: Executing endpoint qc_getconfig Oct 02 09:25:49 volumio volumio[1271]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 09:25:49 volumio qobuz-connect[6748]: 20261002 09:25:49.593 [6748.6748] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 09:25:49 volumio qobuz-connect[6748]: 20261002 09:25:49.600 [6748.6748] INFO VolumeManager: [0x7f2138]: Setting new playback volume: 75 Oct 02 09:25:49 volumio qobuz-connect[6748]: 20261002 09:25:49.600 [6748.6748] INFO VolumeManager: [0x7f2138]: Setting new mute state: 0 Oct 02 09:25:49 volumio qobuz-connect[6748]: 20261002 09:25:49.600 [6748.6748] INFO AudioStreamManager: [0x7f1e90]: Setting new audio download buffer size: 1048576 Oct 02 09:25:49 volumio qobuz-connect[6748]: qobuz-connect-daemon: /home/paolo/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 02 09:25:49 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 02 09:25:49 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 02 09:25:51 volumio volumio[1271]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 02 09:25:51 volumio volumio[1271]: info: CoreStateMachine::removeQueueItem Oct 02 09:25:51 volumio volumio[1271]: info: CoreStateMachine::stop Oct 02 09:25:51 volumio volumio[1271]: info: CoreStateMachine::serviceStop Oct 02 09:25:51 volumio volumio[1271]: info: CoreCommandRouter::serviceStop Oct 02 09:25:51 volumio volumio[1271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 09:25:51 volumio vtcs[4699]: [close:85] Entering Oct 02 09:25:51 volumio volumio[1271]: TypeError: Cannot read properties of undefined (reading 'then') Oct 02 09:25:51 volumio volumio[1271]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Oct 02 09:25:51 volumio volumio[1271]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Oct 02 09:25:51 volumio volumio[1271]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Oct 02 09:25:51 volumio volumio[1271]: at Socket.emit (node:events:514:28) Oct 02 09:25:51 volumio volumio[1271]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 02 09:25:51 volumio volumio[1271]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 02 09:25:51 volumio volumio[1271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 09:25:51 volumio vtcs[4699]: [2026-10-02 09:25:51] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 02 09:25:51 volumio vtcs[4699]: [feedThread:276] Exiting Oct 02 09:25:51 volumio vtcs[4699]: [close:100] Exiting Oct 02 09:25:52 volumio sudo[6767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 09:24' Oct 02 09:25:52 volumio sudo[6767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"