-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2026-04-14 15:40:30 CEST. -- Apr 14 15:39:02 motivo volumio[22323]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 14 15:39:02 motivo volumio[22323]: info: Preparing to save Alsa Options, stopping services first Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:02 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioPause Apr 14 15:39:02 motivo volumio[22323]: info: CoreStateMachine::pause Apr 14 15:39:02 motivo volumio[22323]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Analog + I2S + Headphones"}} Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 15:39:02 motivo volumio[22323]: No protocol specified Apr 14 15:39:02 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:02 motivo volumio[22323]: No protocol specified Apr 14 15:39:02 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 15:39:02 motivo volumio[22323]: No protocol specified Apr 14 15:39:02 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:02 motivo volumio[22323]: info: Setting mixer PCM for card Analog + I2S + Headphones Apr 14 15:39:02 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:02 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:02 motivo volumio[22323]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Apr 14 15:39:02 motivo vtcs[22640]: [2026-04-14 15:39:02.595] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 14 15:39:02 motivo vtcs[22640]: [2026-04-14 15:39:02.595] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Apr 14 15:39:02 motivo vtcs[22640]: [2026-04-14 15:39:02.595] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 14 15:39:02 motivo sudo[23678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:02 motivo sudo[23678]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:02 motivo systemd[1]: Stopping Volumio Tidal Connect Service... Apr 14 15:39:02 motivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:02 motivo systemd[1]: vtcs.service: Succeeded. Apr 14 15:39:02 motivo systemd[1]: Stopped Volumio Tidal Connect Service. Apr 14 15:39:02 motivo sudo[23678]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:02 motivo volumio[22323]: No protocol specified Apr 14 15:39:02 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 14 15:39:02 motivo volumio[22323]: info: Updating Volume Controller Parameters: Device: 0 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 14 15:39:02 motivo volumio[22323]: info: Disabling external Volume Control Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 15:39:02 motivo volumio[22323]: info: Preparing to generate the ALSA configuration file Apr 14 15:39:02 motivo sudo[23685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:02 motivo sudo[23685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:02 motivo sudo[23685]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:02 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:02 motivo sudo[23692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 15:39:02 motivo sudo[23692]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:02 motivo volumio[22323]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 15:39:02 motivo volumio[22323]: info: Reading ALSA contributions from plugins. Apr 14 15:39:02 motivo volumio[22323]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 14 15:39:02 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:02 motivo sudo[23692]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:02 motivo sudo[23695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 15:39:02 motivo sudo[23695]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:02 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:02 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:02 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 15:39:02 motivo qobuz-connect[22536]: 20260414 15:39:02.835 [22536.22536] INFO SampleApp: Stopping Local configuration server Apr 14 15:39:02 motivo qobuz-connect[22536]: 20260414 15:39:02.846 [22536.22536] INFO SampleApp: shat down connection on UNIX socket Apr 14 15:39:02 motivo systemd[1]: qobuz-connect.service: Succeeded. Apr 14 15:39:02 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 15:39:02 motivo volumio[22323]: No protocol specified Apr 14 15:39:02 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:02 motivo systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 15:39:02 motivo sudo[23695]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:02 motivo volumio[22323]: No protocol specified Apr 14 15:39:02 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Apr 14 15:39:02 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:02 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:02 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 15:39:02 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:02 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:02 motivo volumio[22323]: info: VolumeController:: Volume=undefined Mute =false Apr 14 15:39:02 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:02 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:02 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:02 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:02 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:02 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:02 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:02.986+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=97 Apr 14 15:39:02 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:02.987+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:02 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:03 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 15:39:03 motivo volumio[22323]: info: Executing endpoint qc_getconfig Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.042 [23698.23698] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.046 [23698.23698] INFO VolumeManager: [0x2526670]: Setting new playback volume: 75 Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.046 [23698.23698] INFO VolumeManager: [0x2526670]: Setting new mute state: 0 Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.046 [23698.23698] INFO AudioStreamManager: [0x25263c8]: Setting new audio download buffer size: 1048576 Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.046 [23698.23698] INFO QobuzConnect: [0x2526f38]: Client initialized! Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.046 [23698.23698] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.063 [23698.23698] INFO LocalConfigManager: [0x25260f0]: Starting Local Configuration server Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.064 [23698.23698] INFO SampleApp: Starting Local configuration server Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.064 [23698.23698] INFO SampleApp: Connected to UNIX socket client 0x251bf60 Apr 14 15:39:03 motivo volumio[22323]: info: Asound.conf file unchanged, so no further update is needed Apr 14 15:39:03 motivo volumio[22323]: info: Output device has changed, restarting MPD Apr 14 15:39:03 motivo sudo[23707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 15:39:03 motivo sudo[23707]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo volumio[22323]: info: Output device has changed, restarting Shairport Sync Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:03 motivo sudo[23707]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo sudo[23710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 15:39:03 motivo sudo[23710]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo systemd[1]: Stopping Music Player Daemon... Apr 14 15:39:03 motivo volumio[22323]: No protocol specified Apr 14 15:39:03 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:03 motivo volumio[22323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 15:39:03 motivo volumio[22323]: No protocol specified Apr 14 15:39:03 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:03 motivo systemd[1]: mpd.service: Succeeded. Apr 14 15:39:03 motivo systemd[1]: Stopped Music Player Daemon. Apr 14 15:39:03 motivo volumio[22323]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo systemd[1]: Starting Music Player Daemon... Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.192 [23698.23698] INFO SampleApp: Playback volume changed: 75 Apr 14 15:39:03 motivo sudo[23719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:03 motivo sudo[23719]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo sudo[23715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 15:39:03 motivo sudo[23715]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo sudo[23722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:03 motivo sudo[23722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo sudo[23715]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo sudo[23730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 15:39:03 motivo sudo[23730]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo sudo[23719]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: Not Reporting Auto name since its the default one Apr 14 15:39:03 motivo sudo[23730]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo sudo[23722]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:03 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:03 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:03 motivo sudo[23737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo sudo[23737]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo volumio[22323]: info: MPD Permissions set Apr 14 15:39:03 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 15:39:03 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.381 [23698.23698] INFO SampleApp: Stopping Local configuration server Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:03 motivo qobuz-connect[23698]: 20260414 15:39:03.392 [23698.23698] INFO SampleApp: shat down connection on UNIX socket Apr 14 15:39:03 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:03 motivo systemd[1]: qobuz-connect.service: Succeeded. Apr 14 15:39:03 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:03 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:03 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:03 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:03.408+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:03 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:03.408+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:03 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:03.409+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:03 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:03.410+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:03 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:03 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:03 motivo systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 15:39:03 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:03 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:03 motivo sudo[23737]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 14 15:39:03 motivo volumio[22323]: No protocol specified Apr 14 15:39:03 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:03 motivo volumio[22323]: info: Executing endpoint qc_getconfig Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 15:39:03 motivo sudo[23750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 14 15:39:03 motivo sudo[23750]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo volumio[22323]: info: Starting Shairport Sync Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.646 [23740.23740] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 15:39:03 motivo sudo[23750]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.658 [23740.23740] INFO VolumeManager: [0x159f670]: Setting new playback volume: 75 Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.658 [23740.23740] INFO VolumeManager: [0x159f670]: Setting new mute state: 0 Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.658 [23740.23740] INFO AudioStreamManager: [0x159f3c8]: Setting new audio download buffer size: 1048576 Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.658 [23740.23740] INFO QobuzConnect: [0x159ff38]: Client initialized! Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.658 [23740.23740] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.675 [23740.23740] INFO LocalConfigManager: [0x159f0f0]: Starting Local Configuration server Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.675 [23740.23740] INFO SampleApp: Starting Local configuration server Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.677 [23740.23740] INFO SampleApp: Connected to UNIX socket client 0x1594f60 Apr 14 15:39:03 motivo sudo[23754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 14 15:39:03 motivo sudo[23754]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 15:39:03 motivo sudo[23757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 15:39:03 motivo sudo[23757]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:03 motivo systemd[1]: Stopping RAAT DAEMON... Apr 14 15:39:03 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:03 motivo systemd[1]: raat-daemon.service: Succeeded. Apr 14 15:39:03 motivo systemd[1]: Stopped RAAT DAEMON. Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:03 motivo volumio[22323]: info: Not Reporting Auto name since its the default one Apr 14 15:39:03 motivo systemd[1]: Started RAAT DAEMON. Apr 14 15:39:03 motivo sudo[23754]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:03 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:03 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 15:39:03 motivo volumio[22323]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 14 15:39:03 motivo volumio[22323]: info: Preparing to save Alsa Options, stopping services first Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioPause Apr 14 15:39:03 motivo volumio[22323]: info: CoreStateMachine::pause Apr 14 15:39:03 motivo volumio[22323]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Analog + I2S + Headphones"}} Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 15:39:03 motivo systemd[1]: shairport-sync.service: Succeeded. Apr 14 15:39:03 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:03 motivo qobuz-connect[23740]: 20260414 15:39:03.798 [23740.23740] INFO SampleApp: Playback volume changed: 75 Apr 14 15:39:03 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:03 motivo sudo[23757]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:03 motivo volumio[22323]: No protocol specified Apr 14 15:39:03 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:03 motivo volumio[22323]: No protocol specified Apr 14 15:39:03 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 15:39:03 motivo volumio[22323]: No protocol specified Apr 14 15:39:03 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:03 motivo volumio[22323]: info: Setting mixer PCM for card Analog + I2S + Headphones Apr 14 15:39:03 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:03 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:03 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:03 motivo volumio[22323]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Apr 14 15:39:03 motivo sudo[23785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:03 motivo sudo[23785]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23785]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo volumio[22323]: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 14 15:39:04 motivo volumio[22323]: info: Updating Volume Controller Parameters: Device: 0 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 14 15:39:04 motivo volumio[22323]: info: Disabling external Volume Control Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 15:39:04 motivo volumio[22323]: info: Preparing to generate the ALSA configuration file Apr 14 15:39:04 motivo sudo[23792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:04 motivo sudo[23792]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23792]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:04 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:04 motivo sudo[23798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 15:39:04 motivo sudo[23798]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo volumio[22323]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 15:39:04 motivo volumio[22323]: info: Reading ALSA contributions from plugins. Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo sudo[23798]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo volumio[22323]: info: Shairport-Sync Started Apr 14 15:39:04 motivo volumio[22323]: info: Raat Daemon started successfully Apr 14 15:39:04 motivo sudo[23801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 15:39:04 motivo sudo[23801]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo volumio[22323]: info: Executing endpoint restartRAATSocket Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 14 15:39:04 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:04 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 14 15:39:04 motivo mpd[23728]: Apr 14 15:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 15:39:04 motivo qobuz-connect[23740]: 20260414 15:39:04.281 [23740.23740] INFO SampleApp: Stopping Local configuration server Apr 14 15:39:04 motivo systemd[1]: Started Music Player Daemon. Apr 14 15:39:04 motivo sudo[23710]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo qobuz-connect[23740]: 20260414 15:39:04.291 [23740.23740] INFO SampleApp: shat down connection on UNIX socket Apr 14 15:39:04 motivo systemd[1]: qobuz-connect.service: Succeeded. Apr 14 15:39:04 motivo volumio[22323]: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 15:39:04 motivo systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 15:39:04 motivo sudo[23801]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo sudo[23812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 14 15:39:04 motivo sudo[23812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23812]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo sudo[23817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 14 15:39:04 motivo sudo[23817]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo volumio[22323]: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 15:39:04 motivo systemd[1]: Stopping RAAT DAEMON... Apr 14 15:39:04 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:04 motivo systemd[1]: raat-daemon.service: Succeeded. Apr 14 15:39:04 motivo systemd[1]: Stopped RAAT DAEMON. Apr 14 15:39:04 motivo volumio[22323]: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 15:39:04 motivo systemd[1]: Started RAAT DAEMON. Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Apr 14 15:39:04 motivo sudo[23817]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: info: VolumeController:: Volume=undefined Mute =false Apr 14 15:39:04 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:04 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:04 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:04.446+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=97 Apr 14 15:39:04 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:04.446+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: Not Reporting Auto name since its the default one Apr 14 15:39:04 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:04 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:04 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:04 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:04 motivo volumio[22323]: info: Raat Daemon started successfully Apr 14 15:39:04 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 15:39:04 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 15:39:04 motivo volumio[22323]: info: Executing endpoint qc_getconfig Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.607 [23809.23809] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 15:39:04 motivo volumio[22323]: info: Executing endpoint restartRAATSocket Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.611 [23809.23809] INFO VolumeManager: [0x1342670]: Setting new playback volume: 75 Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.611 [23809.23809] INFO VolumeManager: [0x1342670]: Setting new mute state: 0 Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.611 [23809.23809] INFO AudioStreamManager: [0x13423c8]: Setting new audio download buffer size: 1048576 Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.611 [23809.23809] INFO QobuzConnect: [0x1342f38]: Client initialized! Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.611 [23809.23809] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:04 motivo volumio[22323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 14 15:39:04 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 15:39:04 motivo volumio[22323]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.639 [23809.23809] INFO LocalConfigManager: [0x13420f0]: Starting Local Configuration server Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.639 [23809.23809] INFO SampleApp: Starting Local configuration server Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.639 [23809.23809] INFO SampleApp: Connected to UNIX socket client 0x1337f60 Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 14 15:39:04 motivo volumio[22323]: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo volumio[22323]: error: updateQueue error: null Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: info: Asound.conf file unchanged, so no further update is needed Apr 14 15:39:04 motivo volumio[22323]: info: Output device has changed, restarting MPD Apr 14 15:39:04 motivo sudo[23856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 14 15:39:04 motivo sudo[23856]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23856]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo sudo[23859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 15:39:04 motivo sudo[23859]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo qobuz-connect[23809]: 20260414 15:39:04.757 [23809.23809] INFO SampleApp: Playback volume changed: 75 Apr 14 15:39:04 motivo volumio[22323]: info: Output device has changed, restarting Shairport Sync Apr 14 15:39:04 motivo sudo[23859]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:04 motivo sudo[23860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 14 15:39:04 motivo sudo[23860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 15:39:04 motivo sudo[23863]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo systemd[1]: Stopping Music Player Daemon... Apr 14 15:39:04 motivo systemd[1]: Stopping RAAT DAEMON... Apr 14 15:39:04 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:04 motivo systemd[1]: raat-daemon.service: Succeeded. Apr 14 15:39:04 motivo systemd[1]: Stopped RAAT DAEMON. Apr 14 15:39:04 motivo volumio[22323]: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo volumio[22323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 15:39:04 motivo volumio[22323]: No protocol specified Apr 14 15:39:04 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:04 motivo volumio[22323]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:04 motivo systemd[1]: Started RAAT DAEMON. Apr 14 15:39:04 motivo sudo[23860]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo systemd[1]: mpd.service: Succeeded. Apr 14 15:39:04 motivo systemd[1]: Stopped Music Player Daemon. Apr 14 15:39:04 motivo systemd[1]: Starting Music Player Daemon... Apr 14 15:39:04 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:04 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:04 motivo volumio[22323]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 14 15:39:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:04 motivo sudo[23876]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 15:39:04 motivo sudo[23876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:04 motivo sudo[23883]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23876]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo sudo[23888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:04 motivo sudo[23888]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:04 motivo sudo[23888]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:04 motivo sudo[23883]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:05 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:05 motivo sudo[23896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo sudo[23896]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:05 motivo sudo[23896]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo volumio[22323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 14 15:39:05 motivo volumio[22323]: info: Executing endpoint restartRAATSocket Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 14 15:39:05 motivo volumio[22323]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 14 15:39:05 motivo volumio[22323]: info: MPD Permissions set Apr 14 15:39:05 motivo volumio[22323]: info: Raat Daemon started successfully Apr 14 15:39:05 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:05 motivo sudo[23898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 15:39:05 motivo sudo[23898]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:05 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:05 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:05.081+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:05 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:05.082+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:05 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 15:39:05 motivo qobuz-connect[23809]: 20260414 15:39:05.098 [23809.23809] INFO SampleApp: Stopping Local configuration server Apr 14 15:39:05 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:05 motivo qobuz-connect[23809]: 20260414 15:39:05.109 [23809.23809] INFO SampleApp: shat down connection on UNIX socket Apr 14 15:39:05 motivo systemd[1]: qobuz-connect.service: Succeeded. Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:05 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:05 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 15:39:05 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:05.121+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:05 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:05.122+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:05 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 15:39:05 motivo sudo[23898]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 15:39:05 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:05 motivo volumio[22323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 14 15:39:05 motivo volumio[22323]: info: Executing endpoint qc_getconfig Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.367 [23901.23901] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.375 [23901.23901] INFO VolumeManager: [0x218d670]: Setting new playback volume: 75 Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.375 [23901.23901] INFO VolumeManager: [0x218d670]: Setting new mute state: 0 Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.375 [23901.23901] INFO AudioStreamManager: [0x218d3c8]: Setting new audio download buffer size: 1048576 Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.375 [23901.23901] INFO QobuzConnect: [0x218df38]: Client initialized! Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.375 [23901.23901] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.400 [23901.23901] INFO LocalConfigManager: [0x218d0f0]: Starting Local Configuration server Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.400 [23901.23901] INFO SampleApp: Starting Local configuration server Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.401 [23901.23901] INFO SampleApp: Connected to UNIX socket client 0x2182f60 Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: Not Reporting Auto name since its the default one Apr 14 15:39:05 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 15:39:05 motivo volumio[22323]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 15:39:05 motivo volumio[22323]: info: Starting Shairport Sync Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:05 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:05 motivo qobuz-connect[23901]: 20260414 15:39:05.517 [23901.23901] INFO SampleApp: Playback volume changed: 75 Apr 14 15:39:05 motivo sudo[23915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 15:39:05 motivo sudo[23915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo volumio[22323]: info: Preparing to generate the ALSA configuration file Apr 14 15:39:05 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 15:39:05 motivo systemd[1]: shairport-sync.service: Succeeded. Apr 14 15:39:05 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 15:39:05 motivo volumio[22323]: info: Reading ALSA contributions from plugins. Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 14 15:39:05 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:05 motivo sudo[23915]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo volumio[22323]: No protocol specified Apr 14 15:39:05 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:05 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:05 motivo sudo[23923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 14 15:39:05 motivo sudo[23923]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:05 motivo volumio[22323]: info: Shairport-Sync Started Apr 14 15:39:05 motivo sudo[23923]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:05 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:05 motivo volumio[22323]: info: Asound.conf file written Apr 14 15:39:05 motivo sudo[23925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 14 15:39:05 motivo sudo[23925]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo systemd[1]: Stopping RAAT DAEMON... Apr 14 15:39:05 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:05 motivo systemd[1]: raat-daemon.service: Succeeded. Apr 14 15:39:05 motivo systemd[1]: Stopped RAAT DAEMON. Apr 14 15:39:05 motivo sudo[23928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 15:39:05 motivo sudo[23928]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo sudo[23928]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo systemd[1]: Started RAAT DAEMON. Apr 14 15:39:05 motivo sudo[23925]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo volumio[22323]: No protocol specified Apr 14 15:39:05 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:05 motivo volumio[22323]: info: Output device has changed, restarting MPD Apr 14 15:39:05 motivo sudo[23939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 15:39:05 motivo sudo[23939]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo sudo[23939]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:05 motivo volumio[22323]: info: Output device has changed, restarting Shairport Sync Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:05 motivo sudo[23947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 15:39:05 motivo sudo[23947]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:05 motivo systemd[1]: mpd.service: Succeeded. Apr 14 15:39:05 motivo systemd[1]: Stopped Music Player Daemon. Apr 14 15:39:05 motivo volumio[22323]: No protocol specified Apr 14 15:39:05 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:05 motivo volumio[22323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 15:39:05 motivo volumio[22323]: No protocol specified Apr 14 15:39:05 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:05 motivo volumio[22323]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:05 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:05 motivo volumio[22323]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 14 15:39:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:05 motivo systemd[1]: Starting Music Player Daemon... Apr 14 15:39:05 motivo sudo[23956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:05 motivo sudo[23956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo sudo[23959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:05 motivo sudo[23959]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:05 motivo sudo[23952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 15:39:05 motivo sudo[23952]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo sudo[23952]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:06 motivo sudo[23968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 15:39:06 motivo sudo[23968]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:06 motivo volumio[22323]: info: MPD Permissions set Apr 14 15:39:06 motivo volumio[22323]: info: Raat Daemon started successfully Apr 14 15:39:06 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:06 motivo sudo[23956]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo sudo[23968]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo sudo[23959]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:06 motivo sudo[23972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 15:39:06 motivo sudo[23972]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:06 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:06 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:06 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:06 motivo qobuz-connect[23901]: 20260414 15:39:06.129 [23901.23901] INFO SampleApp: Stopping Local configuration server Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.130+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.131+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:06 motivo qobuz-connect[23901]: 20260414 15:39:06.140 [23901.23901] INFO SampleApp: shat down connection on UNIX socket Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:06 motivo systemd[1]: qobuz-connect.service: Succeeded. Apr 14 15:39:06 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 15:39:06 motivo volumio[22323]: info: Executing endpoint restartRAATSocket Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 14 15:39:06 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.222+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.222+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:06 motivo sudo[23972]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 15:39:06 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:06 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:06 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 15:39:06 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:06 motivo volumio[22323]: info: Executing endpoint qc_getconfig Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.334 [23978.23978] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 15:39:06 motivo volumio[22323]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:06 motivo volumio[22323]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.337 [23978.23978] INFO VolumeManager: [0x2247670]: Setting new playback volume: 75 Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.337 [23978.23978] INFO VolumeManager: [0x2247670]: Setting new mute state: 0 Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.337 [23978.23978] INFO AudioStreamManager: [0x22473c8]: Setting new audio download buffer size: 1048576 Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.337 [23978.23978] INFO QobuzConnect: [0x2247f38]: Client initialized! Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.337 [23978.23978] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.351 [23978.23978] INFO LocalConfigManager: [0x22470f0]: Starting Local Configuration server Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.351 [23978.23978] INFO SampleApp: Starting Local configuration server Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.351 [23978.23978] INFO SampleApp: Connected to UNIX socket client 0x223cf60 Apr 14 15:39:06 motivo volumio[22323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:06 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 15:39:06 motivo volumio[22323]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 15:39:06 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:06 motivo volumio[22323]: info: Starting Shairport Sync Apr 14 15:39:06 motivo sudo[23994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 15:39:06 motivo sudo[23994]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo sudo[23997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 15:39:06 motivo sudo[23997]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo systemd[1]: Started Volumio Tidal Connect Service. Apr 14 15:39:06 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:06 motivo sudo[23994]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 15:39:06 motivo systemd[1]: shairport-sync.service: Succeeded. Apr 14 15:39:06 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:06 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:06 motivo qobuz-connect[23978]: 20260414 15:39:06.483 [23978.23978] INFO SampleApp: Playback volume changed: 75 Apr 14 15:39:06 motivo sudo[23997]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: Not Reporting Auto name since its the default one Apr 14 15:39:06 motivo volumio[22323]: info: Preparing to generate the ALSA configuration file Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: Shairport-Sync Started Apr 14 15:39:06 motivo volumio[22323]: info: RAAT: Requesting Headphone Status Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 15:39:06 motivo volumio[22323]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 15:39:06 motivo volumio[22323]: info: Reading ALSA contributions from plugins. Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:06 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:06 motivo volumio[22323]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:06 motivo volumio[22323]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 14 15:39:06 motivo sudo[24018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 15:39:06 motivo sudo[24018]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo sudo[24018]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo volumio[22323]: No protocol specified Apr 14 15:39:06 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 14 15:39:06 motivo volumio[22323]: info: CoreStateMachine::getcurrentVolume Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 15:39:06 motivo sudo[24025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 14 15:39:06 motivo sudo[24025]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo sudo[24025]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo volumio[22323]: info: MCU Signalled Headphone Mode Disabled Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 15:39:06 motivo volumio[22323]: info: Reporting Headphone State: false Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:06 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:06 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:06 motivo volumio[22323]: info: Executing endpoint tc_getconfig Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 14 15:39:06 motivo sudo[24028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 14 15:39:06 motivo vtcs[23998]: STARTING TidalConnect services, version: 1.6.1 Apr 14 15:39:06 motivo sudo[24028]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:06 motivo vtcs[23998]: STARTED TidalConnect services. Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:06 motivo volumio[22323]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 14 15:39:06 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:06 motivo systemd[1]: Stopping RAAT DAEMON... Apr 14 15:39:06 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:06 motivo systemd[1]: raat-daemon.service: Succeeded. Apr 14 15:39:06 motivo systemd[1]: Stopped RAAT DAEMON. Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:06 motivo volumio[22323]: info: Executing endpoint tc_connect Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 14 15:39:06 motivo volumio[22323]: info: Connecting to TidalConnect Apr 14 15:39:06 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:06 motivo systemd[1]: Started RAAT DAEMON. Apr 14 15:39:06 motivo sudo[24028]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::servicePushState Apr 14 15:39:06 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::servicePushState Apr 14 15:39:06 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.881+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.882+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.884+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.884+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:06 motivo volumio[22323]: info: VolumeController:: Volume=undefined Mute =false Apr 14 15:39:06 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:06 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:06 motivo volumio[22323]: info: CoreStateMachine::updateTrackBlock Apr 14 15:39:06 motivo volumio[22323]: info: CorePlayQueue::getTrackBlock Apr 14 15:39:06 motivo volumio[22323]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.942+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=97 Apr 14 15:39:06 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:06.943+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:06 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:06 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:06 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:06 motivo volumio[22323]: info: Raat Daemon started successfully Apr 14 15:39:06 motivo volumio[22323]: info: Asound.conf file written Apr 14 15:39:07 motivo sudo[24058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 15:39:07 motivo sudo[24058]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo sudo[24058]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo mpd[23967]: Apr 14 15:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 15:39:07 motivo systemd[1]: Started Music Player Daemon. Apr 14 15:39:07 motivo sudo[23947]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo sudo[23863]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo volumio[22323]: No protocol specified Apr 14 15:39:07 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:07 motivo volumio[22323]: info: Output device has changed, restarting MPD Apr 14 15:39:07 motivo sudo[24065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 15:39:07 motivo sudo[24065]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo volumio[22323]: info: Output device has changed, restarting Shairport Sync Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:07 motivo sudo[24065]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo sudo[24068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 15:39:07 motivo sudo[24068]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo systemd[1]: Stopping Music Player Daemon... Apr 14 15:39:07 motivo volumio[22323]: No protocol specified Apr 14 15:39:07 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:07 motivo volumio[22323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 15:39:07 motivo volumio[22323]: No protocol specified Apr 14 15:39:07 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo systemd[1]: mpd.service: Succeeded. Apr 14 15:39:07 motivo systemd[1]: Stopped Music Player Daemon. Apr 14 15:39:07 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo vtcs[23998]: [2026-04-14 15:39:07.202] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 14 15:39:07 motivo vtcs[23998]: [2026-04-14 15:39:07.202] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 14 15:39:07 motivo systemd[1]: Starting Music Player Daemon... Apr 14 15:39:07 motivo sudo[24077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:07 motivo sudo[24077]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo systemd[1]: Stopping Volumio Tidal Connect Service... Apr 14 15:39:07 motivo sudo[24079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 15:39:07 motivo sudo[24079]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:07 motivo systemd[1]: vtcs.service: Succeeded. Apr 14 15:39:07 motivo systemd[1]: Stopped Volumio Tidal Connect Service. Apr 14 15:39:07 motivo sudo[24077]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo sudo[24073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 15:39:07 motivo sudo[24073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo sudo[24079]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo sudo[24073]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo volumio[22323]: info: Executing endpoint restartRAATSocket Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 14 15:39:07 motivo volumio[22323]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 14 15:39:07 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:07 motivo sudo[24087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 15:39:07 motivo sudo[24087]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo volumio[22323]: info: RAAT: Requesting Headphone Status Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:07 motivo sudo[24087]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:07 motivo volumio[22323]: info: MPD Permissions set Apr 14 15:39:07 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:07 motivo sudo[24094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 15:39:07 motivo sudo[24094]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 15:39:07 motivo qobuz-connect[23978]: 20260414 15:39:07.421 [23978.23978] INFO SampleApp: Stopping Local configuration server Apr 14 15:39:07 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:07 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:07 motivo qobuz-connect[23978]: 20260414 15:39:07.431 [23978.23978] INFO SampleApp: shat down connection on UNIX socket Apr 14 15:39:07 motivo systemd[1]: qobuz-connect.service: Succeeded. Apr 14 15:39:07 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 15:39:07 motivo sudo[24094]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo volumio[22323]: info: VolumeController:: Volume=undefined Mute =false Apr 14 15:39:07 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:07 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:07.514+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=97 Apr 14 15:39:07 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:07.515+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:07 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:07 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:07 motivo volumio[22323]: info: MCU Signalled Headphone Mode Disabled Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 15:39:07 motivo volumio[22323]: info: Reporting Headphone State: false Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:07 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:07.548+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:07 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:07.549+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:07 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:07.552+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:07 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:07.553+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:07 motivo volumio[22323]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:07 motivo volumio[22323]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:07 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 15:39:07 motivo volumio[22323]: info: QobuzConnect: setDeactiveState invoked Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo sudo[24107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 15:39:07 motivo sudo[24107]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 14 15:39:07 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:07 motivo volumio[22323]: info: Executing endpoint qc_getconfig Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 15:39:07 motivo systemd[1]: Started Volumio Tidal Connect Service. Apr 14 15:39:07 motivo sudo[24107]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.727 [24100.24100] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.732 [24100.24100] INFO VolumeManager: [0x2b0a670]: Setting new playback volume: 75 Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.734 [24100.24100] INFO VolumeManager: [0x2b0a670]: Setting new mute state: 0 Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.734 [24100.24100] INFO AudioStreamManager: [0x2b0a3c8]: Setting new audio download buffer size: 1048576 Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.734 [24100.24100] INFO QobuzConnect: [0x2b0af38]: Client initialized! Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.734 [24100.24100] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.754 [24100.24100] INFO LocalConfigManager: [0x2b0a0f0]: Starting Local Configuration server Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.754 [24100.24100] INFO SampleApp: Starting Local configuration server Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.755 [24100.24100] INFO SampleApp: Connected to UNIX socket client 0x2afff60 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: Not Reporting Auto name since its the default one Apr 14 15:39:07 motivo volumio[22323]: info: RAAT: Requesting Headphone Status Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:07 motivo volumio[22323]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 15:39:07 motivo volumio[22323]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 15:39:07 motivo volumio[22323]: info: Starting Shairport Sync Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 14 15:39:07 motivo sudo[24124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 15:39:07 motivo sudo[24124]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 15:39:07 motivo systemd[1]: shairport-sync.service: Succeeded. Apr 14 15:39:07 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:07 motivo volumio[22323]: No protocol specified Apr 14 15:39:07 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:07 motivo qobuz-connect[24100]: 20260414 15:39:07.877 [24100.24100] INFO SampleApp: Playback volume changed: 75 Apr 14 15:39:07 motivo volumio[22323]: info: MCU Signalled Headphone Mode Disabled Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 15:39:07 motivo volumio[22323]: info: Reporting Headphone State: false Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:07 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:07 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 15:39:07 motivo sudo[24130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 14 15:39:07 motivo volumio[22323]: info: Executing endpoint tc_getconfig Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 14 15:39:07 motivo sudo[24130]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:07 motivo sudo[24124]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo vtcs[24114]: STARTING TidalConnect services, version: 1.6.1 Apr 14 15:39:07 motivo vtcs[24114]: STARTED TidalConnect services. Apr 14 15:39:07 motivo volumio[22323]: info: Shairport-Sync Started Apr 14 15:39:07 motivo volumio[22323]: info: Executing endpoint tc_connect Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 14 15:39:07 motivo volumio[22323]: info: Connecting to TidalConnect Apr 14 15:39:07 motivo sudo[24130]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::servicePushState Apr 14 15:39:07 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:07 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::servicePushState Apr 14 15:39:07 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:07 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:07 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:08 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:08 motivo sudo[24143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 14 15:39:08 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:08 motivo sudo[24143]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:08 motivo volumio[22323]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 14 15:39:08 motivo systemd[1]: Stopping RAAT DAEMON... Apr 14 15:39:08 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 14 15:39:08 motivo systemd[1]: raat-daemon.service: Succeeded. Apr 14 15:39:08 motivo systemd[1]: Stopped RAAT DAEMON. Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.041+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.041+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.052+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=96 Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.053+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:08 motivo systemd[1]: Started RAAT DAEMON. Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 14 15:39:08 motivo volumio[22323]: info: CoreStateMachine::getcurrentVolume Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 15:39:08 motivo sudo[24143]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:08 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 15:39:08 motivo volumio[22323]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 15:39:08 motivo volumio[22323]: info: Raat Daemon started successfully Apr 14 15:39:08 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:08 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:08 motivo volumio[22323]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 14 15:39:08 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:08 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:08 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:08 motivo volumio[22323]: info: Executing endpoint restartRAATSocket Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 14 15:39:08 motivo volumio[22323]: info: VolumeController:: Volume=undefined Mute =false Apr 14 15:39:08 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:08 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:08 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:08 motivo volumio[22323]: info: CoreStateMachine::updateTrackBlock Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrackBlock Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.342+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=97 Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.343+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:08 motivo volumio[22323]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:08 motivo volumio[22323]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:08 motivo volumio[22323]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 14 15:39:08 motivo volumio[22323]: xcb_connection_has_error() returned true Apr 14 15:39:08 motivo mpd[24090]: Apr 14 15:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 15:39:08 motivo systemd[1]: Started Music Player Daemon. Apr 14 15:39:08 motivo sudo[24068]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:08 motivo sudo[24175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 15:39:08 motivo sudo[24175]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:08 motivo volumio[22323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 14 15:39:08 motivo sudo[24175]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:08 motivo volumio[22323]: error: MPD error: The expression evaluated to a falsy value: Apr 14 15:39:08 motivo volumio[22323]: assert.ok(self.idling) Apr 14 15:39:08 motivo volumio[22323]: error: The expression evaluated to a falsy value: Apr 14 15:39:08 motivo volumio[22323]: assert.ok(self.idling) Apr 14 15:39:08 motivo volumio[22323]: error: updateQueue error: null Apr 14 15:39:08 motivo volumio[22323]: info: VolumeController:: Volume=undefined Mute =false Apr 14 15:39:08 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:08 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:08 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:08 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.565+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=0 volume=97 Apr 14 15:39:08 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:08.568+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id=tidal://song/471041397 title="Pièces de clavecin, Troisième Suite: I. Le Coucou" Apr 14 15:39:08 motivo volumio[22323]: error: updateQueue error: null Apr 14 15:39:08 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:08 motivo volumio[22323]: info: RAAT: Requesting Headphone Status Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 15:39:08 motivo volumio[22323]: info: MCU Signalled Headphone Mode Disabled Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 15:39:08 motivo volumio[22323]: info: Reporting Headphone State: false Apr 14 15:39:08 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:08 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:09 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:09 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:09 motivo volumio[22323]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:09 motivo volumio[22323]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:09 motivo sudo[24190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 15:39:09 motivo sudo[24190]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:09 motivo sudo[24190]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:09 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:09 motivo volumio[22323]: info: TidalConnect service started! Apr 14 15:39:09 motivo volumio[22323]: info: TidalConnect service started! Apr 14 15:39:09 motivo volumio[22323]: info: RAAT: Requesting Headphone Status Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 15:39:09 motivo volumio[22323]: info: MCU Signalled Headphone Mode Disabled Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 15:39:09 motivo volumio[22323]: info: Reporting Headphone State: false Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:09 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:09 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:09 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:09 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:09 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:10 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:10 motivo volumio[22323]: info: TidalConnect service stoped! Apr 14 15:39:10 motivo volumio[22323]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:10 motivo volumio[22323]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 15:39:10 motivo sudo[24202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 15:39:10 motivo sudo[24202]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:10 motivo sudo[24202]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:10 motivo volumio[22323]: info: TidalConnect service started! Apr 14 15:39:10 motivo volumio[22323]: info: RAAT: Requesting Headphone Status Apr 14 15:39:10 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 15:39:10 motivo volumio[22323]: info: MCU Signalled Headphone Mode Disabled Apr 14 15:39:10 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 15:39:10 motivo volumio[22323]: info: Reporting Headphone State: false Apr 14 15:39:10 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:10 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 15:39:10 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:11 motivo volumio[22323]: info: Executing endpoint metavolumio Apr 14 15:39:11 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 15:39:11 motivo volumio[22323]: info: Executing endpoint metavolumio Apr 14 15:39:11 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 15:39:11 motivo volumio[22323]: info: Executing endpoint metavolumio Apr 14 15:39:11 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 15:39:11 motivo volumio[22323]: info: TidalConnect service started! Apr 14 15:39:12 motivo volumio[22323]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 15:39:12 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 15:39:12 motivo volumio[22323]: info: TidalConnect service started! Apr 14 15:39:12 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 14 15:39:12 motivo sudo[24209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 14 15:39:12 motivo sudo[24209]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:12 motivo sudo[24214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 14 15:39:12 motivo sudo[24214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:12 motivo sudo[24209]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:12 motivo sudo[24214]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:12 motivo sudo[24223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 14 15:39:12 motivo sudo[24223]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:12 motivo sudo[24223]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:12 motivo sudo[24228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 14 15:39:12 motivo sudo[24228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:12 motivo sudo[24228]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:12 motivo sudo[24235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 15:39:12 motivo sudo[24235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:12 motivo sudo[24235]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:12 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 14 15:39:12 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 14 15:39:12 motivo sudo[24238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 15:39:12 motivo sudo[24238]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:12 motivo sudo[24238]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:12 motivo sudo[24241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 15:39:12 motivo sudo[24241]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:13 motivo volumio[22323]: info: TidalConnect service started! Apr 14 15:39:16 motivo sudo[24241]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:17 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:17 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:17 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:17 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:17.076+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" available=true connected=true macAddress=2c:cf:67:3f:1f:f1 ip4Address=192.168.1.152/24 ip6Address= ssid=MyNET_VC6TR Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:17 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:17 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:17 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:17 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:18 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:18.023+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 14 15:39:18 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:18 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:18 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:18 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:18 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:18 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:18 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:18 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:18 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:19 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:19 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:19 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:19 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:19 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:19 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:20 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:20 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:20 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:20 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:20 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:20 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:20 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:25 motivo volumio[22323]: info: CoreCommandRouter::volumioSeek Apr 14 15:39:25 motivo volumio[22323]: info: CoreStateMachine::seek Apr 14 15:39:25 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:25 motivo volumio[22323]: info: TRACKBLOCK {"album":"Nature","albumUri":"tidal://album/471041396","albumart":"https://resources.tidal.com/images/817ea014/4d3c/43fc/b024/e6eec7aa1861/640x640.jpg","artist":"Tiffany Poon","artistUri":"tidal://artist/5654094","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":110,"explicit":false,"name":"Pièces de clavecin, Troisième Suite: I. Le Coucou","samplerate":"44.1 KHz","service":"tidal","title":"Pièces de clavecin, Troisième Suite: I. Le Coucou","trackType":"tidal","tracknumber":1,"type":"track","uri":"tidal://song/471041397","volumeNumber":1} Apr 14 15:39:25 motivo volumio[22323]: info: CoreStateMachine::startPlaybackTimer Apr 14 15:39:25 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:39:25 motivo volumio[22323]: info: [1776173965519] ControllerTidal::seek Apr 14 15:39:25 motivo volumio[22323]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 15:39:25 motivo volumio[22323]: info: ControllerMpd::seek Apr 14 15:39:25 motivo volumio[22323]: info: CoreStateMachine::pushState Apr 14 15:39:25 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 15:39:25 motivo volumio[22323]: info: CoreCommandRouter::volumioPushState Apr 14 15:39:25 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:25 motivo volumio[22323]: info: MRS: Pushing multiroomSync output update for this device Apr 14 15:39:25 motivo volumio[22323]: info: MRS: Pushing multiroomSync output Apr 14 15:39:25 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:25.539+02:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 14 15:39:25 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:25.539+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" state=STATUS_STOPPED positionMs=41000 volume=97 Apr 14 15:39:25 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:25.540+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" id= title= Apr 14 15:39:25 motivo volumio[22323]: error: [2@0] {seek} Bad song index Apr 14 15:39:25 motivo volumio[22323]: info: Updating RAAT Signal Path Apr 14 15:39:27 motivo volumio[22323]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Apr 14 15:39:27 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Apr 14 15:39:27 motivo sudo[24272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Apr 14 15:39:27 motivo sudo[24272]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:27 motivo sudo[24272]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:27 motivo sudo[24274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Apr 14 15:39:27 motivo sudo[24274]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:27 motivo sudo[24274]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:27 motivo volumio[22323]: info: Permissions for /etc/network/interfaces set Apr 14 15:39:27 motivo volumio[22323]: info: Restarting Wired Network Apr 14 15:39:27 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 14 15:39:27 motivo sudo[24277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Apr 14 15:39:27 motivo sudo[24277]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:27 motivo avahi-daemon[704]: Withdrawing address record for 192.168.1.151 on eth0. Apr 14 15:39:27 motivo avahi-daemon[704]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.151. Apr 14 15:39:27 motivo avahi-daemon[704]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.160.46. Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: pid 24278 deleted IP address 192.168.1.151/24 Apr 14 15:39:27 motivo avahi-daemon[704]: Withdrawing address record for 169.254.160.46 on eth0. Apr 14 15:39:27 motivo avahi-daemon[704]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.160.46. Apr 14 15:39:27 motivo avahi-daemon[704]: Interface eth0.IPv4 no longer relevant for mDNS. Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: deleting route to 192.168.1.0/24 Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: deleting default route via 192.168.1.1 Apr 14 15:39:27 motivo dhcpcd[975]: eth0: pid 24278 deleted IP address 192.168.1.151/24 Apr 14 15:39:27 motivo dhcpcd[975]: eth0: deleting route to 192.168.1.0/24 Apr 14 15:39:27 motivo sudo[24277]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:27 motivo volumio[22323]: info: Discovery: A device disappeared from network Apr 14 15:39:27 motivo sudo[24281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Apr 14 15:39:27 motivo sudo[24281]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:27 motivo kernel: bcmgenet fd580000.ethernet eth0: Link is Down Apr 14 15:39:27 motivo dhcpcd[975]: eth0: rebinding lease of 192.168.1.151 Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: rebinding lease of 192.168.1.151 Apr 14 15:39:27 motivo dhcpcd[975]: dhcp_readbpf: eth0: Network is down Apr 14 15:39:27 motivo dhcpcd[1023]: arp_read: eth0: Network is down Apr 14 15:39:27 motivo sudo[24281]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:27 motivo sudo[24319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Apr 14 15:39:27 motivo sudo[24319]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:27 motivo kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Apr 14 15:39:27 motivo kernel: bcmgenet fd580000.ethernet eth0: Link is Down Apr 14 15:39:27 motivo dhcpcd[1023]: dhcp_readbpf: eth0: Network is down Apr 14 15:39:27 motivo sudo[24319]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:27 motivo dhcpcd[975]: arp_read: eth0: Network is down Apr 14 15:39:27 motivo dhcpcd[975]: eth0: carrier lost Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: pid 24278 deleted IP address 169.254.160.46/16 Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: deleting route to 169.254.0.0/16 Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: probing for an IPv4LL address Apr 14 15:39:27 motivo dhcpcd[1023]: eth0: carrier lost Apr 14 15:39:28 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:28 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:28 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:28 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:28 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:28 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:28.325+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" available=true connected=false macAddress= ip4Address= ip6Address= Apr 14 15:39:28 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:28 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:28 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:28 motivo ifplugd(eth0)[834]: Link beat lost. Apr 14 15:39:29 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:29.203+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 14 15:39:29 motivo sudo[24409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 15:39:29 motivo sudo[24409]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:29 motivo sudo[24409]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:29 motivo sudo[24412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 15:39:29 motivo sudo[24412]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:29 motivo sudo[24412]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:29 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.115 UA: Mozilla/5.0 (Linux; Android 15; 2306EPN60G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 15:39:29 motivo sudo[24415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 15:39:29 motivo sudo[24415]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:29 motivo sudo[24415]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:29 motivo sudo[24418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 15:39:29 motivo sudo[24418]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:29 motivo sudo[24418]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:29 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.115 UA: Mozilla/5.0 (Linux; Android 15; 2306EPN60G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::volumioGetQueue Apr 14 15:39:29 motivo volumio[22323]: info: CoreStateMachine::getQueue Apr 14 15:39:29 motivo volumio[22323]: info: CorePlayQueue::getQueue Apr 14 15:39:29 motivo volumio[22323]: info: Listing playlists Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 15:39:29 motivo volumio[22323]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 14 15:39:29 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:29 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 15:39:29 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 15:39:29 motivo ntpd[835]: Deleting interface #6 eth0, 192.168.1.151#123, interface stats: received=100, sent=107, dropped=0, active_time=256 secs Apr 14 15:39:29 motivo ntpd[835]: 95.110.254.234 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 172.232.208.229 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 151.22.209.90 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 172.232.209.103 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 162.159.200.123 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 193.204.114.232 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 185.157.229.254 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 185.19.184.35 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 162.159.200.1 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 37.247.53.178 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 85.199.214.99 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: 204.216.214.76 local addr 192.168.1.151 -> Apr 14 15:39:29 motivo ntpd[835]: Deleting interface #7 eth0, 169.254.160.46#123, interface stats: received=0, sent=0, dropped=0, active_time=251 secs Apr 14 15:39:30 motivo dhcpcd[1023]: eth0: carrier acquired Apr 14 15:39:30 motivo dhcpcd[975]: eth0: carrier acquired Apr 14 15:39:30 motivo kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Apr 14 15:39:30 motivo dhcpcd[975]: eth0: confile file changed, expiring leases Apr 14 15:39:30 motivo dhcpcd[1023]: eth0: confile file changed, expiring leases Apr 14 15:39:30 motivo dhcpcd[1023]: eth0: IAID 67:3f:1f:f0 Apr 14 15:39:30 motivo dhcpcd[975]: eth0: IAID 67:3f:1f:f0 Apr 14 15:39:30 motivo dhcpcd[975]: eth0: soliciting an IPv6 router Apr 14 15:39:31 motivo dhcpcd[1023]: eth0: soliciting an IPv6 router Apr 14 15:39:31 motivo dhcpcd[1023]: eth0: rebinding lease of 192.168.1.151 Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 15:39:31 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:31.400+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" available=true connected=false macAddress= ip4Address= ip6Address= Apr 14 15:39:31 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:31 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 15:39:31 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:31 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:31 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:31 motivo ifplugd(eth0)[834]: Link beat detected. Apr 14 15:39:31 motivo dhcpcd[975]: eth0: rebinding lease of 192.168.1.151 Apr 14 15:39:32 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 15:39:32 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:32 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:32 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:32 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:32 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:32 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:32 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:32.285+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 14 15:39:33 motivo volumio[22323]: info: CoreCommandRouter::volumioGetQueue Apr 14 15:39:33 motivo volumio[22323]: info: CoreStateMachine::getQueue Apr 14 15:39:33 motivo volumio[22323]: info: CorePlayQueue::getQueue Apr 14 15:39:33 motivo volumio[22323]: info: Executing endpoint metavolumio Apr 14 15:39:33 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 15:39:34 motivo volumio[22323]: info: Reporting MCU Network Status: 2 Apr 14 15:39:34 motivo volumio[22323]: info: Volumio Network Manager: Network status updated: 2 Apr 14 15:39:34 motivo dhcpcd[1023]: eth0: probing address 192.168.1.151/24 Apr 14 15:39:35 motivo dhcpcd[975]: eth0: probing address 192.168.1.151/24 Apr 14 15:39:37 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:37 motivo volumio[22323]: info: Discovery: Networking Restart detected, restarting advertisement and browsing Apr 14 15:39:37 motivo volumio[22323]: info: Discovery: Restarting Advertising Apr 14 15:39:37 motivo volumio[22323]: info: Discovery: Stopping existing advertisement Apr 14 15:39:37 motivo volumio[22323]: info: Discovery: Restarting Browsing Apr 14 15:39:37 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Apr 14 15:39:37 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Apr 14 15:39:37 motivo volumio[22323]: info: Refreshing Cached IP Addresses Apr 14 15:39:37 motivo sudo[24438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 14 15:39:37 motivo sudo[24438]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:37 motivo sudo[24438]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:37 motivo systemd[1]: upmpdcli.service: Succeeded. Apr 14 15:39:37 motivo sudo[24441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 15:39:37 motivo sudo[24441]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:37 motivo sudo[24441]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:37 motivo sudo[24443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 15:39:37 motivo sudo[24443]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:37 motivo sudo[24443]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:38 motivo volumio[22323]: info: Preload queue cleared Apr 14 15:39:38 motivo volumio[22323]: info: Discovery: A device disappeared from network Apr 14 15:39:39 motivo volumio[22323]: info: Executing endpoint metavolumio Apr 14 15:39:39 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 15:39:39 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 15:39:39 motivo dhcpcd[1023]: eth0: leased 192.168.1.151 for 43320 seconds Apr 14 15:39:39 motivo avahi-daemon[704]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.151. Apr 14 15:39:39 motivo avahi-daemon[704]: New relevant interface eth0.IPv4 for mDNS. Apr 14 15:39:39 motivo dhcpcd[1023]: eth0: adding route to 192.168.1.0/24 Apr 14 15:39:39 motivo dhcpcd[1023]: eth0: adding default route via 192.168.1.1 Apr 14 15:39:39 motivo avahi-daemon[704]: Registering new address record for 192.168.1.151 on eth0.IPv4. Apr 14 15:39:40 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:40 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:40 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:40.275+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" available=true connected=true macAddress=2c:cf:67:3f:1f:f0 ip4Address=192.168.1.151/24 ip6Address= Apr 14 15:39:40 motivo dhcpcd[975]: eth0: leased 192.168.1.151 for 43320 seconds Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:40 motivo dhcpcd[975]: eth0: adding route to 192.168.1.0/24 Apr 14 15:39:40 motivo dhcpcd[975]: eth0: adding default route via 192.168.1.1 Apr 14 15:39:40 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:40.794+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.115:43830,192.168.1.115:59938,00:00:00:00:00:00%06 @ 0x38be240" available=true connected=true macAddress=2c:cf:67:3f:1f:f0 ip4Address=192.168.1.151/24 ip6Address= Apr 14 15:39:40 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:40 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:40 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:41 motivo sudo[24529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 15:39:41 motivo sudo[24529]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:41 motivo sudo[24529]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:41 motivo sudo[24532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 15:39:41 motivo sudo[24532]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:41 motivo sudo[24532]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:41 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.115 UA: Mozilla/5.0 (Linux; Android 15; 2306EPN60G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 14 15:39:41 motivo sudo[24535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 15:39:41 motivo sudo[24535]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:41 motivo sudo[24535]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:41 motivo sudo[24538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 15:39:41 motivo sudo[24538]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:41 motivo sudo[24538]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:41 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.115 UA: Mozilla/5.0 (Linux; Android 15; 2306EPN60G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::volumioGetQueue Apr 14 15:39:41 motivo volumio[22323]: info: CoreStateMachine::getQueue Apr 14 15:39:41 motivo volumio[22323]: info: CorePlayQueue::getQueue Apr 14 15:39:41 motivo volumio[22323]: info: Listing playlists Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 15:39:41 motivo volumio[22323]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 14 15:39:41 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:41 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 15:39:41 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 15:39:41 motivo ntpd[835]: Listen normally on 9 eth0 192.168.1.151:123 Apr 14 15:39:41 motivo ntpd[835]: new interface(s) found: waking up resolver Apr 14 15:39:42 motivo volumio5-onboarding[1661]: time=2026-04-14T15:39:42.199+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 14 15:39:42 motivo volumio[22323]: info: Discovery: Started advertising with name: Motivo Apr 14 15:39:42 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 15:39:43 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: adding fad60a08-da69-4caa-9f50-a6d01cf359e8 Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: Found device Motivo Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: this is already registered, fad60a08-da69-4caa-9f50-a6d01cf359e8 Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: Found device Motivo Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: this is already registered, fad60a08-da69-4caa-9f50-a6d01cf359e8 Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: Found device Motivo Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: this is already registered, fad60a08-da69-4caa-9f50-a6d01cf359e8 Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: Found device Motivo Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 15:39:43 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:43 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:43 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:44 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:44 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:44 motivo volumio[22323]: info: Reporting MCU Network Status: 3 Apr 14 15:39:44 motivo volumio[22323]: info: Volumio Network Manager: Network status updated: 3 Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:44 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:44 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:44 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:44 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:45 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:45 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:45 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:45 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:39:45 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:39:45 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:39:45 motivo ntpd[835]: Soliciting pool server 2603:c020:8017:3e00::123:123 Apr 14 15:39:47 motivo sudo[24561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 15:39:47 motivo sudo[24561]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:47 motivo sudo[24561]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:48 motivo sudo[24564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 15:39:48 motivo sudo[24564]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:48 motivo sudo[24564]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:48 motivo sudo[24567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 15:39:48 motivo sudo[24567]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 15:39:48 motivo systemd[1]: Started UPnP Renderer front-end to MPD. Apr 14 15:39:48 motivo sudo[24567]: pam_unix(sudo:session): session closed for user root Apr 14 15:39:48 motivo volumio[22323]: info: Upmpdcli Daemon Started Apr 14 15:39:48 motivo volumio[24569]: writing RSA key Apr 14 15:39:51 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 15:39:59 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 15:39:59 motivo volumio[22323]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 15:39:59 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 15:39:59 motivo volumio[22323]: info: Received Get System Version Apr 14 15:39:59 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 15:39:59 motivo volumio[22323]: info: Received Get System Info Apr 14 15:39:59 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:39:59 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:39:59 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:39:59 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:39:59 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:40:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:40:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:40:04 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:40:04 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:40:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:40:04 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.151:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Apr 14 15:40:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:40:04 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:40:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 15:40:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 15:40:05 motivo volumio[22323]: info: Discovery: Getting this device information Apr 14 15:40:05 motivo volumio[22323]: info: CoreCommandRouter::volumioGetState Apr 14 15:40:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 15:40:05 motivo volumio[22323]: verbose: New Socket.io Connection to 192.168.1.152:3000 from 192.168.1.115 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Apr 14 15:40:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 15:40:05 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 15:40:07 motivo volumio[22323]: Searching plugin music_service/tidal Apr 14 15:40:07 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 15:40:07 motivo volumio[22323]: info: Refreshing TIDAL token Apr 14 15:40:08 motivo volumio[22323]: info: searchTIDALUri took 489 milliseconds Apr 14 15:40:08 motivo volumio[22323]: info: search took 920 milliseconds Apr 14 15:40:08 motivo volumio[22323]: info: All search sources collected, pushing search results Apr 14 15:40:08 motivo volumio[22323]: Searching plugin music_service/tidal Apr 14 15:40:08 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 15:40:08 motivo volumio[22323]: info: searchTIDALUri took 371 milliseconds Apr 14 15:40:08 motivo volumio[22323]: info: search took 372 milliseconds Apr 14 15:40:08 motivo volumio[22323]: info: All search sources collected, pushing search results Apr 14 15:40:11 motivo volumio[22323]: Searching plugin music_service/tidal Apr 14 15:40:11 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 15:40:12 motivo volumio[22323]: info: searchTIDALUri took 417 milliseconds Apr 14 15:40:12 motivo volumio[22323]: info: search took 418 milliseconds Apr 14 15:40:12 motivo volumio[22323]: info: All search sources collected, pushing search results Apr 14 15:40:12 motivo volumio[22323]: Searching plugin music_service/tidal Apr 14 15:40:12 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 15:40:13 motivo volumio[22323]: info: searchTIDALUri took 348 milliseconds Apr 14 15:40:13 motivo volumio[22323]: info: search took 349 milliseconds Apr 14 15:40:13 motivo volumio[22323]: info: All search sources collected, pushing search results Apr 14 15:40:14 motivo volumio[22323]: Searching plugin music_service/tidal Apr 14 15:40:14 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 15:40:14 motivo volumio[22323]: info: searchTIDALUri took 310 milliseconds Apr 14 15:40:14 motivo volumio[22323]: info: search took 312 milliseconds Apr 14 15:40:14 motivo volumio[22323]: info: All search sources collected, pushing search results Apr 14 15:40:19 motivo volumio[22323]: Searching plugin music_service/tidal Apr 14 15:40:19 motivo volumio[22323]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 15:40:19 motivo volumio[22323]: info: searchTIDALUri took 334 milliseconds Apr 14 15:40:19 motivo volumio[22323]: info: search took 336 milliseconds Apr 14 15:40:20 motivo volumio[22323]: info: All search sources collected, pushing search results Apr 14 15:40:20 motivo ntpd[835]: Soliciting pool server 212.45.144.206 Apr 14 15:40:21 motivo ntpd[835]: Soliciting pool server 80.211.238.32 Apr 14 15:40:29 motivo volumio[22323]: info: CorePlayQueue::getTrack 0 Apr 14 15:40:29 motivo volumio[22323]: info: CorePlayQueue::getTrack 1 Apr 14 15:40:29 motivo volumio[22323]: info: Prefetching next song Apr 14 15:40:29 motivo volumio[22323]: info: [1776174029640] ControllerTidal::prefetch Apr 14 15:40:29 motivo volumio[22323]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 15:40:29 motivo volumio[22323]: TypeError: Cannot read property 'highestSoundQuality' of undefined Apr 14 15:40:29 motivo volumio[22323]: at ControllerTidal.getProperSoundQuality (/volumio/app/plugins/music_service/tidal/tidal_real:1:19583) Apr 14 15:40:29 motivo volumio[22323]: at ControllerTidal.prefetch (/volumio/app/plugins/music_service/tidal/tidal_real:1:20287) Apr 14 15:40:29 motivo volumio[22323]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Apr 14 15:40:29 motivo volumio[22323]: at listOnTimeout (internal/timers.js:554:17) Apr 14 15:40:29 motivo volumio[22323]: at processTimers (internal/timers.js:497:7) Apr 14 15:40:29 motivo volumio[22323]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 15:40:30 motivo sudo[24677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 15:39 Apr 14 15:40:30 motivo sudo[24677]: 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="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 02:37:02 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="b74c3af44d427b3f5795469c952e44b4"