-- Logs begin at Tue 2026-03-24 19:17:15 CET, end at Fri 2026-04-03 17:30:22 CEST. -- Apr 03 17:29:17 mercuryv2 volumio[1204]: verbose: New Socket.io Connection to 192.168.178.40 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Apr 03 17:29:17 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:17 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 03 17:29:17 mercuryv2 volumio[1204]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 03 17:29:17 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 17:29:17 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 17:29:17 mercuryv2 volumio[1204]: info: Listing playlists Apr 03 17:29:18 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume40 Apr 03 17:29:18 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:18 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:29:18 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:18 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:18 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:18 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:18 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:18.468+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=40 Apr 03 17:29:18 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:18.469+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:18 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:18 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:18 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:20 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume60 Apr 03 17:29:21 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:21 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:29:21 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:21 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:21 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:21 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:21 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:21.036+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:21 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:21.037+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:21 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:21 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:21 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:26 mercuryv2 volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 17:29:27 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 17:29:27 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 03 17:29:27 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:35 mercuryv2 volumio[1204]: info: Restoring Previous Volume level: 60 false false Apr 03 17:29:35 mercuryv2 volumio[1204]: info: Volume configurations have been set Apr 03 17:29:35 mercuryv2 volumio[1204]: info: QobuzConnect: setDeactiveState invoked Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:35 mercuryv2 vtcs[1733]: [2026-04-03 17:29:35.060] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 03 17:29:35 mercuryv2 vtcs[1733]: [2026-04-03 17:29:35.061] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 03 17:29:35 mercuryv2 vtcs[1733]: [2026-04-03 17:29:35.061] [tisoc] [error] [SpkconServer.cpp:476] socket write error. fd=3 errorno=32 error=Broken pipe Apr 03 17:29:35 mercuryv2 sudo[3868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 03 17:29:35 mercuryv2 sudo[3868]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:35 mercuryv2 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 03 17:29:35 mercuryv2 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 03 17:29:35 mercuryv2 systemd[1]: vtcs.service: Succeeded. Apr 03 17:29:35 mercuryv2 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 03 17:29:35 mercuryv2 sudo[3868]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:35 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 03 17:29:35 mercuryv2 volumio[1204]: info: Updating Volume Controller Parameters: Device: 5 Name: Bel Canto uLinkUSB Audio 2.0 Mixer: Bel Canto uLinkAudio 2.0 Output Playback Sw Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 03 17:29:35 mercuryv2 volumio[1204]: info: Disabling external Volume Control Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 17:29:35 mercuryv2 sudo[3875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 03 17:29:35 mercuryv2 sudo[3875]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:35 mercuryv2 sudo[3875]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:35 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 03 17:29:35 mercuryv2 sudo[3881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 03 17:29:35 mercuryv2 sudo[3881]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:35 mercuryv2 volumio[1204]: Unable to parse: Apr 03 17:29:35 mercuryv2 volumio[1204]: Simple mixer control 'Bel Canto uLinkAudio 2.0 Output Playback Sw',0 Apr 03 17:29:35 mercuryv2 volumio[1204]: Capabilities: pswitch Apr 03 17:29:35 mercuryv2 volumio[1204]: Playback channels: Front Left - Front Right Apr 03 17:29:35 mercuryv2 volumio[1204]: Mono: Apr 03 17:29:35 mercuryv2 volumio[1204]: Front Left: Playback [on] Apr 03 17:29:35 mercuryv2 volumio[1204]: Front Right: Playback [on] Apr 03 17:29:35 mercuryv2 volumio[1204]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 03 17:29:35 mercuryv2 sudo[3881]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:35 mercuryv2 sudo[3885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 03 17:29:35 mercuryv2 sudo[3885]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:35 mercuryv2 systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 03 17:29:35 mercuryv2 qobuz-connect[1678]: 20260403 17:29:35.360 [1678.1678] INFO SampleApp: Stopping Local configuration server Apr 03 17:29:35 mercuryv2 qobuz-connect[1678]: 20260403 17:29:35.370 [1678.1678] INFO SampleApp: shat down connection on UNIX socket Apr 03 17:29:35 mercuryv2 systemd[1]: qobuz-connect.service: Succeeded. Apr 03 17:29:35 mercuryv2 systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 03 17:29:35 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 17:29:35 mercuryv2 systemd[1]: Started Volumio Qobuz Connect Service. Apr 03 17:29:35 mercuryv2 sudo[3885]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:35 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 17:29:35 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 03 17:29:35 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 03 17:29:35 mercuryv2 volumio[1204]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 03 17:29:35 mercuryv2 volumio[1204]: info: QobuzConnect: setDeactiveState invoked Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:35 mercuryv2 volumio[1204]: Unable to parse: Apr 03 17:29:35 mercuryv2 volumio[1204]: Simple mixer control 'Bel Canto uLinkAudio 2.0 Output Playback Sw',0 Apr 03 17:29:35 mercuryv2 volumio[1204]: Capabilities: pswitch Apr 03 17:29:35 mercuryv2 volumio[1204]: Playback channels: Front Left - Front Right Apr 03 17:29:35 mercuryv2 volumio[1204]: Mono: Apr 03 17:29:35 mercuryv2 volumio[1204]: Front Left: Playback [on] Apr 03 17:29:35 mercuryv2 volumio[1204]: Front Right: Playback [on] Apr 03 17:29:35 mercuryv2 volumio[1204]: info: VolumeController:: Volume=undefined Mute =false Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:35 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:35 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:35 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:35.471+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:35 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:35.471+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:35 mercuryv2 volumio[1204]: info: Executing endpoint qc_getconfig Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.509 [3889.3889] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 03 17:29:35 mercuryv2 volumio[1204]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 03 17:29:35 mercuryv2 volumio[1204]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.513 [3889.3889] INFO VolumeManager: [0xe7f890]: Setting new playback volume: 75 Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.513 [3889.3889] INFO VolumeManager: [0xe7f890]: Setting new mute state: 0 Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.513 [3889.3889] INFO QobuzConnect: [0xe80260]: Client initialized! Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.513 [3889.3889] INFO SampleApp: Starting Avahi advertising, name: MercuryV2, service name: _qobuz-connect._tcp Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.519 [3889.3889] INFO LocalConfigManager: [0xe7f170]: Starting Local Configuration server Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.519 [3889.3889] INFO SampleApp: Starting Local configuration server Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.519 [3889.3889] INFO SampleApp: Connected to UNIX socket client 0xe74f60 Apr 03 17:29:35 mercuryv2 qobuz-connect[3889]: 20260403 17:29:35.659 [3889.3889] INFO SampleApp: Playback volume changed: 75 Apr 03 17:29:35 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: TidalConnect service stoped! Apr 03 17:29:38 mercuryv2 volumio[1204]: info: TidalConnect service stoped! Apr 03 17:29:38 mercuryv2 volumio[1204]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 03 17:29:38 mercuryv2 volumio[1204]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 03 17:29:38 mercuryv2 sudo[3905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 03 17:29:38 mercuryv2 sudo[3905]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:38 mercuryv2 systemd[1]: Started Volumio Tidal Connect Service. Apr 03 17:29:38 mercuryv2 sudo[3905]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:38 mercuryv2 volumio[1204]: info: Executing endpoint tc_getconfig Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 03 17:29:38 mercuryv2 vtcs[3907]: STARTING TidalConnect services, version: 1.6.1 Apr 03 17:29:38 mercuryv2 vtcs[3907]: STARTED TidalConnect services. Apr 03 17:29:38 mercuryv2 volumio[1204]: info: Executing endpoint tc_connect Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 03 17:29:38 mercuryv2 volumio[1204]: info: Connecting to TidalConnect Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::servicePushState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:38 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::servicePushState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:38 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 03 17:29:38 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:38.666+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:38 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:38.667+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:38 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:38.674+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:38 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:39 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume60 Apr 03 17:29:39 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:39 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:39 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:39 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:39 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:39 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:39.586+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:39 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:39.586+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:39 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:39 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:39 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:41 mercuryv2 volumio[1204]: info: TidalConnect service started! Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Apr 03 17:29:44 mercuryv2 sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 17:29:44 mercuryv2 sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:44 mercuryv2 sudo[3943]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:44 mercuryv2 volumio[1204]: info: Restoring Previous Volume level: 60 false false Apr 03 17:29:44 mercuryv2 volumio[1204]: info: Volume configurations have been set Apr 03 17:29:44 mercuryv2 volumio[1204]: info: QobuzConnect: setDeactiveState invoked Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:44 mercuryv2 vtcs[3907]: [2026-04-03 17:29:44.217] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 03 17:29:44 mercuryv2 vtcs[3907]: [2026-04-03 17:29:44.217] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 03 17:29:44 mercuryv2 sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 17:29:44 mercuryv2 sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:44 mercuryv2 sudo[3948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 03 17:29:44 mercuryv2 sudo[3948]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:44 mercuryv2 systemd[1]: musicservicesshield.service: Succeeded. Apr 03 17:29:44 mercuryv2 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 03 17:29:44 mercuryv2 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 03 17:29:44 mercuryv2 systemd[1]: Stopping Music Player Daemon... Apr 03 17:29:44 mercuryv2 systemd[1]: mpd.service: Succeeded. Apr 03 17:29:44 mercuryv2 systemd[1]: Stopped Music Player Daemon. Apr 03 17:29:44 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 03 17:29:44 mercuryv2 volumio[1204]: info: Updating Volume Controller Parameters: Device: 5 Name: Bel Canto uLinkUSB Audio 2.0 Mixer: Bel Canto uLinkAudio 2.0 Output Playback Sw Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 03 17:29:44 mercuryv2 volumio[1204]: info: Disabling external Volume Control Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 17:29:44 mercuryv2 sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 03 17:29:44 mercuryv2 sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:44 mercuryv2 systemd[1]: Starting Music Player Daemon... Apr 03 17:29:44 mercuryv2 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 03 17:29:44 mercuryv2 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 03 17:29:44 mercuryv2 systemd[1]: vtcs.service: Succeeded. Apr 03 17:29:44 mercuryv2 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 03 17:29:44 mercuryv2 sudo[3948]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:44 mercuryv2 sudo[3956]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:44 mercuryv2 sudo[3963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 03 17:29:44 mercuryv2 sudo[3963]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:44 mercuryv2 sudo[3953]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 03 17:29:44 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 03 17:29:44 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 03 17:29:44 mercuryv2 sudo[3953]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:44 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 03 17:29:44 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 03 17:29:44 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 03 17:29:44 mercuryv2 sudo[3953]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:44 mercuryv2 volumio[1204]: Unable to parse: Apr 03 17:29:44 mercuryv2 volumio[1204]: Simple mixer control 'Bel Canto uLinkAudio 2.0 Output Playback Sw',0 Apr 03 17:29:44 mercuryv2 volumio[1204]: Capabilities: pswitch Apr 03 17:29:44 mercuryv2 volumio[1204]: Playback channels: Front Left - Front Right Apr 03 17:29:44 mercuryv2 volumio[1204]: Mono: Apr 03 17:29:44 mercuryv2 volumio[1204]: Front Left: Playback [on] Apr 03 17:29:44 mercuryv2 volumio[1204]: Front Right: Playback [on] Apr 03 17:29:44 mercuryv2 volumio[1204]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 03 17:29:44 mercuryv2 sudo[3963]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:44 mercuryv2 sudo[3968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 03 17:29:44 mercuryv2 sudo[3968]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:44 mercuryv2 volumio[1204]: info: MPD Permissions set Apr 03 17:29:44 mercuryv2 volumio[1204]: Unable to parse: Apr 03 17:29:44 mercuryv2 volumio[1204]: Simple mixer control 'Bel Canto uLinkAudio 2.0 Output Playback Sw',0 Apr 03 17:29:44 mercuryv2 volumio[1204]: Capabilities: pswitch Apr 03 17:29:44 mercuryv2 volumio[1204]: Playback channels: Front Left - Front Right Apr 03 17:29:44 mercuryv2 volumio[1204]: Mono: Apr 03 17:29:44 mercuryv2 volumio[1204]: Front Left: Playback [on] Apr 03 17:29:44 mercuryv2 volumio[1204]: Front Right: Playback [on] Apr 03 17:29:44 mercuryv2 volumio[1204]: info: VolumeController:: Volume=undefined Mute =false Apr 03 17:29:44 mercuryv2 systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:44 mercuryv2 qobuz-connect[3889]: 20260403 17:29:44.627 [3889.3889] INFO SampleApp: Stopping Local configuration server Apr 03 17:29:44 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:44 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:44 mercuryv2 qobuz-connect[3889]: 20260403 17:29:44.642 [3889.3889] INFO SampleApp: shat down connection on UNIX socket Apr 03 17:29:44 mercuryv2 systemd[1]: qobuz-connect.service: Succeeded. Apr 03 17:29:44 mercuryv2 systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:44.677+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:44 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:44.677+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:44 mercuryv2 systemd[1]: Started Volumio Qobuz Connect Service. Apr 03 17:29:44 mercuryv2 sudo[3968]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 17:29:44 mercuryv2 volumio[1204]: xcb_connection_has_error() returned true Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 17:29:44 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 03 17:29:44 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:44 mercuryv2 volumio[1204]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 03 17:29:44 mercuryv2 volumio[1204]: info: QobuzConnect: setDeactiveState invoked Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:44 mercuryv2 volumio[1204]: info: Executing endpoint qc_getconfig Apr 03 17:29:44 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 03 17:29:44 mercuryv2 qobuz-connect[3971]: 20260403 17:29:44.978 [3971.3971] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 03 17:29:44 mercuryv2 qobuz-connect[3971]: 20260403 17:29:44.982 [3971.3971] INFO VolumeManager: [0x510890]: Setting new playback volume: 75 Apr 03 17:29:44 mercuryv2 qobuz-connect[3971]: 20260403 17:29:44.982 [3971.3971] INFO VolumeManager: [0x510890]: Setting new mute state: 0 Apr 03 17:29:44 mercuryv2 qobuz-connect[3971]: 20260403 17:29:44.982 [3971.3971] INFO QobuzConnect: [0x511260]: Client initialized! Apr 03 17:29:44 mercuryv2 qobuz-connect[3971]: 20260403 17:29:44.982 [3971.3971] INFO SampleApp: Starting Avahi advertising, name: MercuryV2, service name: _qobuz-connect._tcp Apr 03 17:29:44 mercuryv2 volumio[1204]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 03 17:29:44 mercuryv2 volumio[1204]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 03 17:29:44 mercuryv2 qobuz-connect[3971]: 20260403 17:29:44.999 [3971.3971] INFO LocalConfigManager: [0x510170]: Starting Local Configuration server Apr 03 17:29:44 mercuryv2 qobuz-connect[3971]: 20260403 17:29:44.999 [3971.3971] INFO SampleApp: Starting Local configuration server Apr 03 17:29:45 mercuryv2 qobuz-connect[3971]: 20260403 17:29:45.000 [3971.3971] INFO SampleApp: Connected to UNIX socket client 0x505f60 Apr 03 17:29:45 mercuryv2 qobuz-connect[3971]: 20260403 17:29:45.127 [3971.3971] INFO SampleApp: Playback volume changed: 75 Apr 03 17:29:45 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:45 mercuryv2 mpd[3967]: Apr 03 17:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 03 17:29:45 mercuryv2 sudo[3946]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:45 mercuryv2 systemd[1]: Started Music Player Daemon. Apr 03 17:29:45 mercuryv2 volumio[1204]: error: updateQueue error: null Apr 03 17:29:45 mercuryv2 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 03 17:29:45 mercuryv2 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 03 17:29:45 mercuryv2 volumio[3986]: cset: --> shielding system active with Apr 03 17:29:45 mercuryv2 volumio[3986]: cset: "system" cpuset of CPUSPEC(1-3) with 154 tasks running Apr 03 17:29:45 mercuryv2 volumio[3986]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Apr 03 17:29:45 mercuryv2 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 03 17:29:47 mercuryv2 volumio[1204]: info: TidalConnect service stoped! Apr 03 17:29:47 mercuryv2 volumio[1204]: info: TidalConnect service stoped! Apr 03 17:29:47 mercuryv2 volumio[1204]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 03 17:29:47 mercuryv2 volumio[1204]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 03 17:29:47 mercuryv2 sudo[4010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 03 17:29:47 mercuryv2 sudo[4010]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 17:29:47 mercuryv2 systemd[1]: Started Volumio Tidal Connect Service. Apr 03 17:29:47 mercuryv2 sudo[4010]: pam_unix(sudo:session): session closed for user root Apr 03 17:29:47 mercuryv2 volumio[1204]: info: Executing endpoint tc_getconfig Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 03 17:29:47 mercuryv2 vtcs[4012]: STARTING TidalConnect services, version: 1.6.1 Apr 03 17:29:47 mercuryv2 vtcs[4012]: STARTED TidalConnect services. Apr 03 17:29:47 mercuryv2 volumio[1204]: info: Executing endpoint tc_connect Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 03 17:29:47 mercuryv2 volumio[1204]: info: Connecting to TidalConnect Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::servicePushState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:47 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::servicePushState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:47 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 03 17:29:47 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:47.773+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:47 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:47.773+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:47 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:47.776+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:47 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:47.776+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:47 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:48 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume60 Apr 03 17:29:48 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:48 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:48 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:48 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:48 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:48 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:48 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:48 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:48.767+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:29:48 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:48 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:48.767+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://192.168.178.21:51647/id/cc83b1b3-840c-4517-acd9-857dbb03c701 title="Love Is Stronger Than Pride" Apr 03 17:29:50 mercuryv2 volumio[1204]: info: TidalConnect service started! Apr 03 17:29:53 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 03 17:29:53 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetQueue Apr 03 17:29:53 mercuryv2 volumio[1204]: info: CoreStateMachine::getQueue Apr 03 17:29:53 mercuryv2 volumio[1204]: info: CorePlayQueue::getQueue Apr 03 17:29:53 mercuryv2 volumio[1204]: info: Preload queue cleared Apr 03 17:29:54 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 03 17:29:54 mercuryv2 volumio[1204]: info: Preload queue cleared Apr 03 17:29:56 mercuryv2 volumio[1204]: info: Preload queue cleared Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::ClearQueue Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::stop Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::stPlaybackTimer Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::updateTrackBlock Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrackBlock Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:29:56 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:29:56 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::serviceStop Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:56 mercuryv2 volumio[1204]: info: ControllerMpd::stop Apr 03 17:29:56 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::clearPlayQueue Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::saveQueue Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushQueue Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::addQueueItems Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::addQueueItems Apr 03 17:29:56 mercuryv2 volumio[1204]: info: Preload queue cleared Apr 03 17:29:56 mercuryv2 volumio[1204]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s24896 Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 03 17:29:56 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:56.497+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_STOPPED positionMs=0 volume=60 Apr 03 17:29:56 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:29:56.497+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id= title= Apr 03 17:29:56 mercuryv2 volumio[1204]: info: sendMpdCommand stop took 57 milliseconds Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushQueue Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::saveQueue Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::updateTrackBlock Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrackBlock Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPlay Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::play index 0 Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::stop Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::play index undefined Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreStateMachine::startPlaybackTimer Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 17:29:56 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 03 17:29:56 mercuryv2 volumio[1204]: info: [1775230196854] ControllerWebradio::clearAddPlayTrack Apr 03 17:29:56 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 17:29:56 mercuryv2 volumio[1204]: info: sendMpdCommand stop took 4 milliseconds Apr 03 17:29:56 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 17:29:56 mercuryv2 volumio[1204]: info: Apr 03 17:29:56 mercuryv2 volumio[1204]: ---------------------------- MPD announces system playlist update Apr 03 17:29:56 mercuryv2 volumio[1204]: info: Ignoring MPD Status Update Apr 03 17:29:56 mercuryv2 volumio[1204]: info: sendMpdCommand clear took 3 milliseconds Apr 03 17:29:56 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand load "http://liveradio.swr.de/tn8jep3/swr3/" Apr 03 17:29:56 mercuryv2 volumio[1204]: error: updateQueue error: null Apr 03 17:29:56 mercuryv2 volumio[1204]: info: ------------------------------ 4ms Apr 03 17:29:59 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand add "http://liveradio.swr.de/tn8jep3/swr3/" Apr 03 17:29:59 mercuryv2 volumio[1204]: info: Apr 03 17:29:59 mercuryv2 volumio[1204]: ---------------------------- MPD announces system playlist update Apr 03 17:29:59 mercuryv2 volumio[1204]: info: Ignoring MPD Status Update Apr 03 17:29:59 mercuryv2 volumio[1204]: info: sendMpdCommand add "http://liveradio.swr.de/tn8jep3/swr3/" took 2 milliseconds Apr 03 17:29:59 mercuryv2 volumio[1204]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 17:29:59 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand play Apr 03 17:29:59 mercuryv2 volumio[1204]: info: ------------------------------ 4ms Apr 03 17:29:59 mercuryv2 volumio[1204]: info: sendMpdCommand play took 3 milliseconds Apr 03 17:30:00 mercuryv2 volumio[1204]: info: Apr 03 17:30:00 mercuryv2 volumio[1204]: ---------------------------- MPD announces state update: player Apr 03 17:30:00 mercuryv2 volumio[1204]: info: ControllerMpd::getState Apr 03 17:30:00 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Apr 03 17:30:01 mercuryv2 volumio[1204]: ---------------------------- MPD announces system playlist update Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Ignoring MPD Status Update Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Apr 03 17:30:01 mercuryv2 volumio[1204]: ---------------------------- MPD announces state update: player Apr 03 17:30:01 mercuryv2 volumio[1204]: info: sendMpdCommand status took 489 milliseconds Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ControllerMpd::getState Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::parseState Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ------------------------------ 6ms Apr 03 17:30:01 mercuryv2 volumio[1204]: info: sendMpdCommand status took 5 milliseconds Apr 03 17:30:01 mercuryv2 volumio[1204]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::parseState Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::parseTrackInfo Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ControllerMpd::pushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::servicePushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: CURRENT POSITION 0 Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::syncState stateService play Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::syncState currentStatus stop Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ------------------------------ 503ms Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Apr 03 17:30:01 mercuryv2 volumio[1204]: ---------------------------- MPD announces system playlist update Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Ignoring MPD Status Update Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Apr 03 17:30:01 mercuryv2 volumio[1204]: ---------------------------- MPD announces state update: player Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ControllerMpd::getState Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand status Apr 03 17:30:01 mercuryv2 volumio[1204]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::parseTrackInfo Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ControllerMpd::pushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::servicePushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":514,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I kissed a girl / Katy Perry","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: CURRENT POSITION 0 Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::syncState stateService play Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::syncState currentStatus play Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Received an update from plugin. extracting info from payload Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.433+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.434+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.437+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.438+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ------------------------------ 68ms Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ------------------------------ 56ms Apr 03 17:30:01 mercuryv2 volumio[1204]: info: sendMpdCommand status took 54 milliseconds Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::parseState Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 17:30:01 mercuryv2 volumio[1204]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: ControllerMpd::parseTrackInfo Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ControllerMpd::pushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::servicePushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CorePlayQueue::getTrack 0 Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I kissed a girl / Katy Perry","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 03 17:30:01 mercuryv2 volumio[1204]: verbose: CURRENT POSITION 0 Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::syncState stateService play Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::syncState currentStatus play Apr 03 17:30:01 mercuryv2 volumio[1204]: info: Received an update from plugin. extracting info from payload Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:01 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:01 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.491+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.492+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.494+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=0 volume=60 Apr 03 17:30:01 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:01.494+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:01 mercuryv2 volumio[1204]: info: ------------------------------ 108ms Apr 03 17:30:05 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume80 Apr 03 17:30:05 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:05 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:30:05 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:05 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:05 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:05 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:05 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:05.589+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=4008 volume=80 Apr 03 17:30:05 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:05.590+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:08 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume95 Apr 03 17:30:08 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:08 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:30:08 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:08 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:08 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:08 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:08 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:08.554+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=7014 volume=95 Apr 03 17:30:08 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:08.555+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:10 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume60 Apr 03 17:30:10 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:10 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:30:10 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:10 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:10 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:10 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:10 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:10.444+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=9018 volume=60 Apr 03 17:30:10 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:10.445+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:11 mercuryv2 volumio[1204]: info: VolumeController::SetAlsaVolume80 Apr 03 17:30:11 mercuryv2 volumio[1204]: info: CoreStateMachine::pushState Apr 03 17:30:11 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 17:30:11 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioPushState Apr 03 17:30:11 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Apr 03 17:30:11 mercuryv2 volumio[1204]: info: MRS: Pushing multiroomSync output Apr 03 17:30:11 mercuryv2 volumio[1204]: info: CoreCommandRouter::volumioGetState Apr 03 17:30:11 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:11.384+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" state=STATUS_PLAYING positionMs=9768 volume=80 Apr 03 17:30:11 mercuryv2 volumio5-onboarding[1338]: time=2026-04-03T17:30:11.385+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:51971,192.168.178.21:52083 @ 0x28d4b40" id=http://liveradio.swr.de/tn8jep3/swr3/ title="I kissed a girl / Katy Perry" Apr 03 17:30:22 mercuryv2 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Apr 03 17:30:22 mercuryv2 volumio[1204]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Apr 03 17:30:22 mercuryv2 volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 17:30:22 mercuryv2 volumio[1204]: TypeError: Cannot read property 'then' of undefined Apr 03 17:30:22 mercuryv2 volumio[1204]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Apr 03 17:30:22 mercuryv2 volumio[1204]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Apr 03 17:30:22 mercuryv2 volumio[1204]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Apr 03 17:30:22 mercuryv2 volumio[1204]: at Socket.emit (events.js:315:20) Apr 03 17:30:22 mercuryv2 volumio[1204]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 03 17:30:22 mercuryv2 volumio[1204]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 03 17:30:22 mercuryv2 volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 17:30:22 mercuryv2 sudo[4110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-03 17:29 Apr 03 17:30:22 mercuryv2 sudo[4110]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" 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 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"