-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-12-09 09:25:42 CET. -- Dec 09 09:24:35 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 09 09:24:35 motivo volumio[941]: info: Loading i18n strings for locale en Dec 09 09:24:35 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 09 09:24:35 motivo volumio[941]: info: Setting timezone to Europe/Rome Dec 09 09:24:35 motivo sudo[2774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 09 09:24:35 motivo sudo[2774]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:35 motivo sudo[2774]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:35 motivo sudo[2777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Dec 09 09:24:35 motivo sudo[2777]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:35 motivo sudo[2777]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:35 motivo sudo[2780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 09 09:24:35 motivo sudo[2780]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:35 motivo sudo[2780]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:35 motivo sudo[2783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Dec 09 09:24:35 motivo sudo[2783]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:35 motivo dbus-daemon[716]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.62' (uid=0 pid=2784 comm="/usr/bin/timedatectl set-timezone Europe/Rome ") Dec 09 09:24:35 motivo systemd[1]: Starting Time & Date Service... Dec 09 09:24:35 motivo dbus-daemon[716]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 09 09:24:35 motivo systemd[1]: Started Time & Date Service. Dec 09 09:24:35 motivo sudo[2783]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:35 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 09 09:24:35 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 09 09:24:35 motivo sudo[2787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 09 09:24:35 motivo sudo[2787]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:35 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:35 motivo volumio[941]: Updating browse sources language Dec 09 09:24:35 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:24:35 motivo volumio[941]: info: Fetching Streaming Services browse cache Dec 09 09:24:38 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 09 09:24:38 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 09 09:24:39 motivo volumio[941]: No protocol specified Dec 09 09:24:39 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 09:24:39 motivo sudo[2787]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:39 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:39 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:39 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:39 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 09:24:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 09:24:39 motivo volumio5-onboarding[1642]: time=2025-12-09T08:24:39.865Z level=INFO msg="BLE descriptor updated" deviceId=084528f9f5d24b9e88cebd7ce9e91a8f deviceName=Motivo deviceModel=0x22c0558 Dec 09 09:24:39 motivo volumio5-onboarding[1642]: time=2025-12-09T08:24:39.869Z level=INFO msg="mDNS descriptor updated" deviceId=084528f9f5d24b9e88cebd7ce9e91a8f deviceName=Motivo deviceModel=0x22c0558 Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 09 09:24:41 motivo volumio[941]: info: Preparing to save Alsa Options, stopping services first Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:41 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::volumioPause Dec 09 09:24:41 motivo volumio[941]: info: CoreStateMachine::pause Dec 09 09:24:41 motivo volumio[941]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"Analog + I2S + Headphones"},"disallowPush":true} Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: info: Setting mixer PCM for card Analog + I2S + Headphones Dec 09 09:24:41 motivo volumio[941]: info: QobuzConnect: setDeactiveState invoked Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:41 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:41 motivo volumio[941]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 09 09:24:41 motivo vtcs[1963]: [2025-12-09 09:24:41.378] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 09 09:24:41 motivo vtcs[1963]: [2025-12-09 09:24:41.378] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 09 09:24:41 motivo sudo[2816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 09 09:24:41 motivo sudo[2816]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo systemd[1]: Stopping Volumio Tidal Connect Service... Dec 09 09:24:41 motivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 09 09:24:41 motivo systemd[1]: vtcs.service: Succeeded. Dec 09 09:24:41 motivo systemd[1]: Stopped Volumio Tidal Connect Service. Dec 09 09:24:41 motivo sudo[2816]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: info: Applying Volume Override Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 09 09:24:41 motivo volumio[941]: info: Updating Volume Controller Parameters: Device: 5 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 09 09:24:41 motivo volumio[941]: info: Enabling external Volume Control Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 09:24:41 motivo volumio[941]: info: Preparing to generate the ALSA configuration file Dec 09 09:24:41 motivo sudo[2822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 09 09:24:41 motivo sudo[2822]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo sudo[2822]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 09 09:24:41 motivo sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 09 09:24:41 motivo sudo[2828]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 09 09:24:41 motivo sudo[2828]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:41 motivo sudo[2832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 09 09:24:41 motivo sudo[2832]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:41 motivo sudo[2836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 09 09:24:41 motivo sudo[2836]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo sudo[2835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 09 09:24:41 motivo sudo[2836]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:41 motivo sudo[2835]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo volumio[941]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 09 09:24:41 motivo volumio[941]: info: Reading ALSA contributions from plugins. Dec 09 09:24:41 motivo volumio[941]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Dec 09 09:24:41 motivo volumio[941]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 09 09:24:41 motivo volumio[941]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 09 09:24:41 motivo volumio[941]: info: Cannot use regular scanning, forcing with ap-force Dec 09 09:24:41 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 09 09:24:41 motivo qobuz-connect[1898]: 20251209 09:24:41.615 [1898.1898] INFO SampleApp: Stopping Local configuration server Dec 09 09:24:41 motivo qobuz-connect[1898]: 20251209 09:24:41.626 [1898.1898] INFO SampleApp: shat down connection on UNIX socket Dec 09 09:24:41 motivo systemd[1]: qobuz-connect.service: Succeeded. Dec 09 09:24:41 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 09 09:24:41 motivo sudo[2840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 09 09:24:41 motivo sudo[2840]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo volumio[941]: command failed: Device or resource busy (-16) Dec 09 09:24:41 motivo volumio[941]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 09 09:24:41 motivo volumio[941]: command failed: Device or resource busy (-16) Dec 09 09:24:41 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:41 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:24:41 motivo volumio[941]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 09 09:24:41 motivo volumio[941]: info: QobuzConnect: setDeactiveState invoked Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:41 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:41 motivo sudo[2840]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:41 motivo systemd[1]: Started Volumio Qobuz Connect Service. Dec 09 09:24:41 motivo sudo[2835]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 09 09:24:41 motivo volumio[941]: info: Executing endpoint qc_getconfig Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.809 [2841.2841] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.813 [2841.2841] INFO VolumeManager: [0x23a9958]: Setting new playback volume: 75 Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.813 [2841.2841] INFO VolumeManager: [0x23a9958]: Setting new mute state: 0 Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.813 [2841.2841] INFO QobuzConnect: [0x23aa328]: Client initialized! Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.813 [2841.2841] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Dec 09 09:24:41 motivo volumio[941]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 09 09:24:41 motivo volumio[941]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.820 [2841.2841] INFO LocalConfigManager: [0x23a9238]: Starting Local Configuration server Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.821 [2841.2841] INFO SampleApp: Starting Local configuration server Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.821 [2841.2841] INFO SampleApp: Connected to UNIX socket client 0x239ef60 Dec 09 09:24:41 motivo volumio[941]: info: Asound.conf file unchanged, so no further update is needed Dec 09 09:24:41 motivo volumio[941]: info: Output device has changed, restarting MPD Dec 09 09:24:41 motivo sudo[2852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 09 09:24:41 motivo sudo[2852]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo volumio[941]: info: Output device has changed, restarting Shairport Sync Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 09:24:41 motivo sudo[2852]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:41 motivo sudo[2855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 09 09:24:41 motivo sudo[2855]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:41 motivo systemd[1]: Stopping Music Player Daemon... Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 09 09:24:41 motivo volumio[941]: No protocol specified Dec 09 09:24:41 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:41 motivo volumio[941]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:41 motivo volumio[941]: info: QobuzConnect: setDeactiveState invoked Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:41 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:41 motivo volumio[941]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 09 09:24:41 motivo systemd[1]: mpd.service: Succeeded. Dec 09 09:24:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:41 motivo systemd[1]: Stopped Music Player Daemon. Dec 09 09:24:41 motivo qobuz-connect[2841]: 20251209 09:24:41.958 [2841.2841] INFO SampleApp: Playback volume changed: 75 Dec 09 09:24:41 motivo systemd[1]: Starting Music Player Daemon... Dec 09 09:24:41 motivo sudo[2864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 09 09:24:41 motivo sudo[2864]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo sudo[2864]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo sudo[2867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 09 09:24:42 motivo sudo[2867]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:24:42 motivo sudo[2874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 09 09:24:42 motivo sudo[2862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 09 09:24:42 motivo sudo[2862]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo sudo[2874]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo sudo[2862]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: Not Reporting Auto name since its the default one Dec 09 09:24:42 motivo sudo[2867]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:42 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:42 motivo sudo[2874]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo volumio[941]: info: MPD Permissions set Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo sudo[2883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo sudo[2883]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 09 09:24:42 motivo qobuz-connect[2841]: 20251209 09:24:42.143 [2841.2841] INFO SampleApp: Stopping Local configuration server Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo qobuz-connect[2841]: 20251209 09:24:42.156 [2841.2841] INFO SampleApp: shat down connection on UNIX socket Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 09 09:24:42 motivo systemd[1]: qobuz-connect.service: Succeeded. Dec 09 09:24:42 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 09 09:24:42 motivo systemd[1]: Started Volumio Qobuz Connect Service. Dec 09 09:24:42 motivo sudo[2883]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo volumio[941]: No protocol specified Dec 09 09:24:42 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:24:42 motivo sudo[2894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 09 09:24:42 motivo sudo[2894]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo volumio[941]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 09 09:24:42 motivo volumio[941]: info: QobuzConnect: setDeactiveState invoked Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:42 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:42 motivo volumio[941]: info: Executing endpoint qc_getconfig Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 09 09:24:42 motivo sudo[2894]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo sudo[2897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 09 09:24:42 motivo sudo[2897]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.288 [2889.2889] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 09 09:24:42 motivo volumio[941]: info: Starting Shairport Sync Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.292 [2889.2889] INFO VolumeManager: [0x299b958]: Setting new playback volume: 75 Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.292 [2889.2889] INFO VolumeManager: [0x299b958]: Setting new mute state: 0 Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.292 [2889.2889] INFO QobuzConnect: [0x299c328]: Client initialized! Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.292 [2889.2889] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Dec 09 09:24:42 motivo systemd[1]: Stopping RAAT DAEMON... Dec 09 09:24:42 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 09 09:24:42 motivo systemd[1]: raat-daemon.service: Succeeded. Dec 09 09:24:42 motivo systemd[1]: Stopped RAAT DAEMON. Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.311 [2889.2889] INFO LocalConfigManager: [0x299b238]: Starting Local Configuration server Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.312 [2889.2889] INFO SampleApp: Starting Local configuration server Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.313 [2889.2889] INFO SampleApp: Connected to UNIX socket client 0x2990f60 Dec 09 09:24:42 motivo volumio[941]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 09 09:24:42 motivo volumio[941]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:24:42 motivo sudo[2903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 09 09:24:42 motivo sudo[2903]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo systemd[1]: Started RAAT DAEMON. Dec 09 09:24:42 motivo sudo[2897]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:42 motivo volumio[941]: info: Raat Daemon started successfully Dec 09 09:24:42 motivo systemd[1]: shairport-sync.service: Succeeded. Dec 09 09:24:42 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:24:42 motivo volumio[941]: info: Executing endpoint restartRAATSocket Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 09 09:24:42 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 09 09:24:42 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:42 motivo sudo[2903]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 09 09:24:42 motivo volumio[941]: info: Shairport-Sync Started Dec 09 09:24:42 motivo qobuz-connect[2889]: 20251209 09:24:42.439 [2889.2889] INFO SampleApp: Playback volume changed: 75 Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 09:24:42 motivo volumio[941]: info: Not Reporting Auto name since its the default one Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:42 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 09 09:24:42 motivo volumio[941]: No protocol specified Dec 09 09:24:42 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:42 motivo sudo[2925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 09 09:24:42 motivo sudo[2925]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo sudo[2925]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo sudo[2927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 09 09:24:42 motivo sudo[2927]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:42 motivo systemd[1]: Stopping RAAT DAEMON... Dec 09 09:24:42 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 09 09:24:42 motivo systemd[1]: raat-daemon.service: Succeeded. Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:42 motivo systemd[1]: Stopped RAAT DAEMON. Dec 09 09:24:42 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 09:24:42 motivo systemd[1]: Started RAAT DAEMON. Dec 09 09:24:42 motivo volumio[941]: info: Retrieving Cloud Streaming UI Dec 09 09:24:42 motivo sudo[2927]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:42 motivo volumio[941]: info: Raat Daemon started successfully Dec 09 09:24:42 motivo volumio[941]: info: Getting Tidal Cloud Configuration Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 09:24:42 motivo volumio[941]: info: Getting Qobuz Cloud Configuration Dec 09 09:24:42 motivo volumio[941]: info: Asking plugin for UI Config Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 09:24:42 motivo volumio[941]: info: Getting Spotify Cloud Configuration Dec 09 09:24:42 motivo volumio[941]: info: Asking plugin for UI Config Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 09:24:42 motivo volumio[941]: info: Saving Spotify Acccount Dec 09 09:24:42 motivo volumio[941]: info: Got it Dec 09 09:24:42 motivo volumio[941]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 09 09:24:42 motivo volumio[941]: info: Executing endpoint restartRAATSocket Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 09 09:24:42 motivo volumio[941]: info: Got Tidal Cloud Configuration Dec 09 09:24:42 motivo volumio[941]: info: Got it Dec 09 09:24:42 motivo volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 09:24:42 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:42 motivo volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 09 09:24:43 motivo mpd[2877]: Dec 09 09:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 09 09:24:43 motivo systemd[1]: Started Music Player Daemon. Dec 09 09:24:43 motivo sudo[2855]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:43 motivo volumio[941]: error: updateQueue error: null Dec 09 09:24:44 motivo volumio[941]: info: TidalConnect service stoped! Dec 09 09:24:44 motivo volumio[941]: info: TidalConnect service stoped! Dec 09 09:24:44 motivo volumio[941]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 09 09:24:44 motivo volumio[941]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 09 09:24:44 motivo sudo[2951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 09 09:24:44 motivo sudo[2951]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 09 09:24:44 motivo systemd[1]: Started Volumio Tidal Connect Service. Dec 09 09:24:44 motivo sudo[2951]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:44 motivo volumio[941]: No protocol specified Dec 09 09:24:44 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 09 09:24:44 motivo volumio[941]: No protocol specified Dec 09 09:24:44 motivo volumio[941]: xcb_connection_has_error() returned true Dec 09 09:24:44 motivo volumio[941]: info: Executing endpoint tc_getconfig Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 09 09:24:44 motivo vtcs[2953]: STARTING TidalConnect services, version: 1.5.2.56 Dec 09 09:24:44 motivo vtcs[2953]: STARTED TidalConnect services. Dec 09 09:24:44 motivo volumio[941]: info: Executing endpoint tc_connect Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 09 09:24:44 motivo volumio[941]: info: Connecting to TidalConnect Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::servicePushState Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::pushState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioPushState Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output update for this device Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 09 09:24:44 motivo volumio[941]: verbose: CURRENT POSITION 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::syncState stateService stop Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::syncState currentStatus stop Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::pushState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioPushState Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output update for this device Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output Dec 09 09:24:44 motivo volumio[941]: info: No code Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::pushState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioPushState Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output update for this device Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::servicePushState Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::pushState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioPushState Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output update for this device Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 09 09:24:44 motivo volumio[941]: verbose: CURRENT POSITION 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::syncState stateService stop Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::syncState currentStatus stop Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::pushState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioPushState Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output update for this device Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output Dec 09 09:24:44 motivo volumio[941]: info: No code Dec 09 09:24:44 motivo volumio[941]: info: CoreStateMachine::pushState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioPushState Dec 09 09:24:44 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:44 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output update for this device Dec 09 09:24:44 motivo volumio[941]: info: MRS: Pushing multiroomSync output Dec 09 09:24:44 motivo volumio[941]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wCm8Y6WpLcSGPBG9FBLtifV44Vi2.084528f9f5d24b9e88cebd7ce9e91a8f.state.mute' Dec 09 09:24:44 motivo volumio[941]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wCm8Y6WpLcSGPBG9FBLtifV44Vi2.084528f9f5d24b9e88cebd7ce9e91a8f.state.mute' Dec 09 09:24:44 motivo volumio[941]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wCm8Y6WpLcSGPBG9FBLtifV44Vi2.084528f9f5d24b9e88cebd7ce9e91a8f.state.mute' Dec 09 09:24:44 motivo volumio[941]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wCm8Y6WpLcSGPBG9FBLtifV44Vi2.084528f9f5d24b9e88cebd7ce9e91a8f.state.mute' Dec 09 09:24:44 motivo volumio[941]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wCm8Y6WpLcSGPBG9FBLtifV44Vi2.084528f9f5d24b9e88cebd7ce9e91a8f.state.mute' Dec 09 09:24:44 motivo volumio[941]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wCm8Y6WpLcSGPBG9FBLtifV44Vi2.084528f9f5d24b9e88cebd7ce9e91a8f.state.mute' Dec 09 09:24:45 motivo volumio[941]: info: TidalConnect service stoped! Dec 09 09:24:45 motivo volumio[941]: info: TidalConnect service stoped! Dec 09 09:24:45 motivo volumio[941]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 09 09:24:45 motivo volumio[941]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 09 09:24:45 motivo sudo[2987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 09 09:24:45 motivo sudo[2987]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:45 motivo volumio[941]: info: RAAT: Requesting Headphone Status Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 09 09:24:45 motivo sudo[2987]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:45 motivo sudo[2832]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:45 motivo volumio[941]: info: MCU Signalled Headphone Mode Disabled Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 09 09:24:45 motivo volumio[941]: info: Reporting Headphone State: false Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:45 motivo volumio[941]: info: Updating RAAT Signal Path Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 09 09:24:45 motivo volumio[941]: info: CoreStateMachine::getcurrentVolume Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::volumioRetrievevolume Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Dec 09 09:24:45 motivo volumio[941]: info: RAAT: Requesting Headphone Status Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 09 09:24:45 motivo volumio[941]: info: MCU Signalled Headphone Mode Disabled Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 09 09:24:45 motivo volumio[941]: info: Reporting Headphone State: false Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:45 motivo volumio[941]: info: Updating RAAT Signal Path Dec 09 09:24:45 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:45 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:45 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 09:24:45 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 09:24:45 motivo volumio5-onboarding[1642]: time=2025-12-09T08:24:45.736Z level=INFO msg="BLE descriptor updated" deviceId=084528f9f5d24b9e88cebd7ce9e91a8f deviceName=Motivo deviceModel=0x20265a0 Dec 09 09:24:45 motivo volumio5-onboarding[1642]: time=2025-12-09T08:24:45.739Z level=INFO msg="mDNS descriptor updated" deviceId=084528f9f5d24b9e88cebd7ce9e91a8f deviceName=Motivo deviceModel=0x20265a0 Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::Reload Ui Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Dec 09 09:24:46 motivo volumio[941]: info: Wizard terminated Successfully Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::volumiosetStartupVolume Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 09:24:46 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:46 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:46 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 09:24:46 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 09:24:46 motivo volumio5-onboarding[1642]: time=2025-12-09T08:24:46.094Z level=INFO msg="BLE descriptor updated" deviceId=084528f9f5d24b9e88cebd7ce9e91a8f deviceName=Motivo deviceModel=0x21829c8 Dec 09 09:24:46 motivo volumio5-onboarding[1642]: time=2025-12-09T08:24:46.100Z level=INFO msg="mDNS descriptor updated" deviceId=084528f9f5d24b9e88cebd7ce9e91a8f deviceName=Motivo deviceModel=0x21829c8 Dec 09 09:24:46 motivo sudo[2991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 09 09:24:46 motivo sudo[2991]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:46 motivo systemd[1]: Stopping MPD Monitor Service... Dec 09 09:24:46 motivo systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 09 09:24:46 motivo systemd[1]: mpd_monitor.service: Succeeded. Dec 09 09:24:46 motivo systemd[1]: Stopped MPD Monitor Service. Dec 09 09:24:46 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:24:46 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:24:46 motivo systemd[1]: Started MPD Monitor Service. Dec 09 09:24:46 motivo mpd_monitor.sh[2993]: MPD Monitor Service: Starting MPD Monitor Service Dec 09 09:24:46 motivo sudo[2991]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:46 motivo volumio[941]: info: Successfully started MPD Monitor Dec 09 09:24:47 motivo sudo[3000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 09:24:47 motivo sudo[3000]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:47 motivo sudo[3000]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:47 motivo sudo[3003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 09:24:47 motivo sudo[3003]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:47 motivo sudo[3003]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:47 motivo volumio[941]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.148 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 09 09:24:47 motivo sudo[3006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 09:24:47 motivo sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:47 motivo sudo[3006]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:47 motivo sudo[3008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 09:24:47 motivo sudo[3008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:47 motivo sudo[3008]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:47 motivo volumio[941]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.148 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:47 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 09:24:47 motivo volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 09:24:47 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:47 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:47 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:47 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:47 motivo volumio[941]: info: Listing playlists Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 09:24:47 motivo volumio[941]: info: TidalConnect service started! Dec 09 09:24:47 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 09:24:48 motivo volumio[941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 11 Dec 09 09:24:48 motivo volumio[941]: info: TidalConnect service started! Dec 09 09:24:48 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:48 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:48 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:49 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 09:24:49 motivo volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 09:24:49 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:49 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:49 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:49 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:49 motivo volumio[941]: info: Listing playlists Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:24:49 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:49 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:49 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:49 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:49 motivo volumio[941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 11 Dec 09 09:24:50 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:24:50 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:50 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:50 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:50 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:50 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:50 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:50 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:51 motivo volumio[941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 12 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 09:24:51 motivo volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 09:24:51 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:51 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: Listing playlists Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:24:51 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:51 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 09:24:51 motivo volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 09:24:51 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:51 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:51 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:51 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:51 motivo volumio[941]: info: Listing playlists Dec 09 09:24:52 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 09:24:52 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 09:24:52 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 09 09:24:53 motivo sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 09:24:53 motivo sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:53 motivo sudo[3089]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:53 motivo sudo[3091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 09:24:53 motivo sudo[3091]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:24:53 motivo sudo[3091]: pam_unix(sudo:session): session closed for user root Dec 09 09:24:53 motivo volumio[941]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.148 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 09 09:24:53 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:53 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 09:24:53 motivo volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 09:24:53 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:53 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: Listing playlists Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:24:53 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:53 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:53 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:53 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:55 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:24:55 motivo volumio[941]: info: Received Get System Info Dec 09 09:24:55 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:24:55 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:24:55 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:24:55 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:24:55 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:24:55 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:24:57 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 09:24:59 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 09:25:00 motivo volumio[941]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.148 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 09 09:25:00 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:00 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:00 motivo volumio[941]: info: CoreCommandRouter::volumioGetQueue Dec 09 09:25:00 motivo volumio[941]: info: CoreStateMachine::getQueue Dec 09 09:25:00 motivo volumio[941]: info: CorePlayQueue::getQueue Dec 09 09:25:00 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 09 09:25:00 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Dec 09 09:25:00 motivo volumio[941]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Dec 09 09:25:00 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Dec 09 09:25:00 motivo volumio[941]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Dec 09 09:25:00 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Dec 09 09:25:03 motivo volumio[941]: info: CALLMETHOD: system_controller system enableSSH true Dec 09 09:25:03 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Dec 09 09:25:03 motivo sudo[3109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Dec 09 09:25:03 motivo sudo[3109]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:03 motivo sudo[3109]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:03 motivo sudo[3111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Dec 09 09:25:03 motivo sudo[3111]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:03 motivo systemd[1]: Reloading. Dec 09 09:25:04 motivo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 09 09:25:04 motivo systemd[1]: Reloading. Dec 09 09:25:04 motivo volumio[941]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.148 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 09 09:25:04 motivo volumio[941]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.148 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:04 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::volumioGetQueue Dec 09 09:25:04 motivo volumio[941]: info: CoreStateMachine::getQueue Dec 09 09:25:04 motivo volumio[941]: info: CorePlayQueue::getQueue Dec 09 09:25:04 motivo volumio[941]: info: Listing playlists Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 09:25:04 motivo volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 09:25:04 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:04 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:04 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:04 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 09:25:04 motivo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 09 09:25:04 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 09:25:04 motivo systemd[1]: Reloading. Dec 09 09:25:05 motivo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 09 09:25:05 motivo sudo[3111]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:05 motivo volumio[941]: info: enable SSH service success Dec 09 09:25:05 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 09 09:25:05 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:25:05 motivo systemd[1]: systemd-timedated.service: Succeeded. Dec 09 09:25:05 motivo volumio-remote-updater[714]: No test mode Dec 09 09:25:05 motivo volumio-remote-updater[714]: No alpha test mode Dec 09 09:25:05 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 09 09:25:05 motivo volumio[941]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 09 09:25:05 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 09 09:25:06 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 09 09:25:06 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:25:06 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:06 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:06 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:06 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:06 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:06 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:06 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo sudo[3167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 09:25:07 motivo sudo[3167]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:25:07 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:07 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:07 motivo sudo[3167]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:07 motivo sudo[3170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 09:25:07 motivo sudo[3170]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:07 motivo sudo[3170]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:07 motivo volumio[941]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.148 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:07 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetQueue Dec 09 09:25:07 motivo volumio[941]: info: CoreStateMachine::getQueue Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getQueue Dec 09 09:25:07 motivo volumio[941]: info: Listing playlists Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 09:25:07 motivo volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 09:25:07 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:07 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 09:25:07 motivo volumio[941]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 09 09:25:07 motivo volumio[941]: info: Received Get System Version Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 09:25:07 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:07 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:07 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:07 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:09 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:25:09 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:09 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:09 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:09 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:09 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:09 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:09 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:11 motivo sshd[3187]: Connection closed by 192.168.1.148 port 45464 [preauth] Dec 09 09:25:14 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 09:25:17 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 09:25:20 motivo volumio[941]: info: CoreCommandRouter::Close All Modals sent Dec 09 09:25:20 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Dec 09 09:25:22 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 09:25:22 motivo volumio[941]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 09 09:25:22 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 09 09:25:22 motivo volumio[941]: info: Received Get System Version Dec 09 09:25:22 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 09:25:22 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:22 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:22 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:22 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:22 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:22 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:22 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:23 motivo sshd[3189]: Accepted password for volumio from 192.168.1.148 port 45468 ssh2 Dec 09 09:25:23 motivo sshd[3189]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Dec 09 09:25:23 motivo systemd[1]: Created slice User Slice of UID 1000. Dec 09 09:25:23 motivo systemd[1]: Starting User Runtime Directory /run/user/1000... Dec 09 09:25:23 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:25:23 motivo systemd-logind[724]: New session 1 of user volumio. Dec 09 09:25:23 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:25:23 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 09:25:23 motivo systemd[1]: Started User Runtime Directory /run/user/1000. Dec 09 09:25:23 motivo systemd[1]: Starting User Manager for UID 1000... Dec 09 09:25:23 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:25:23 motivo systemd[3209]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Dec 09 09:25:23 motivo systemd[3209]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Dec 09 09:25:23 motivo systemd[3209]: Reached target Timers. Dec 09 09:25:23 motivo systemd[3209]: Listening on GnuPG cryptographic agent and passphrase cache. Dec 09 09:25:23 motivo systemd[3209]: Starting D-Bus User Message Bus Socket. Dec 09 09:25:23 motivo systemd[3209]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Dec 09 09:25:23 motivo systemd[3209]: Listening on GnuPG network certificate management daemon. Dec 09 09:25:23 motivo systemd[3209]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 09 09:25:23 motivo systemd[3209]: Reached target Paths. Dec 09 09:25:23 motivo systemd[3209]: Listening on Sound System. Dec 09 09:25:23 motivo systemd[3209]: Listening on D-Bus User Message Bus Socket. Dec 09 09:25:23 motivo systemd[3209]: Reached target Sockets. Dec 09 09:25:23 motivo systemd[3209]: Reached target Basic System. Dec 09 09:25:23 motivo systemd[3209]: Reached target Default. Dec 09 09:25:23 motivo systemd[3209]: Startup finished in 166ms. Dec 09 09:25:23 motivo systemd[1]: Started User Manager for UID 1000. Dec 09 09:25:23 motivo systemd[1]: Started Session 1 of user volumio. Dec 09 09:25:25 motivo sudo[3232]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Dec 09 09:25:25 motivo sudo[3232]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 09 09:25:28 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , myVolumioLogout Dec 09 09:25:28 motivo volumio[941]: info: [MyVolumio PluginManager] Subscribed plan changed to free Dec 09 09:25:28 motivo volumio[941]: info: MyVolumio status changed Dec 09 09:25:28 motivo volumio[941]: info: Streaming services startup Dec 09 09:25:28 motivo volumio[941]: info: Starting Streaming Daemon Dec 09 09:25:28 motivo volumio[941]: info: [MyVolumio PluginManager] Device deactivated, disabling myvolumio plugins... Dec 09 09:25:28 motivo volumio[941]: info: ___________ STOPPING MYVOLUMIO PLUGINS ___________ Dec 09 09:25:28 motivo volumio[941]: info: Dec 09 09:25:28 motivo volumio[941]: info: MYVOLUMIO LOGGED OUT Dec 09 09:25:28 motivo volumio[941]: info: Removing browser output: myVolumio user plan is not superstar Dec 09 09:25:28 motivo volumio[941]: info: Removing audio output: Dec 09 09:25:28 motivo volumio[941]: info: Stoppping Tunnel 1 Dec 09 09:25:28 motivo sudo[3239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 09 09:25:28 motivo sudo[3239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:28 motivo sudo[3239]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:28 motivo volumio[941]: error: Cannot start Volumio Streaming Daemon Dec 09 09:25:28 motivo volumio[941]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 09 09:25:28 motivo volumio[941]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 09 09:25:28 motivo sudo[3242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 09 09:25:28 motivo sudo[3242]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:28 motivo autossh[2556]: received signal to exit (15) Dec 09 09:25:28 motivo systemd[1]: Stopping MyVolumio SSH Tunnel... Dec 09 09:25:28 motivo systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Dec 09 09:25:28 motivo systemd[1]: sshtunnel.service: Succeeded. Dec 09 09:25:28 motivo systemd[1]: Stopped MyVolumio SSH Tunnel. Dec 09 09:25:28 motivo volumio[941]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 09:25:28 motivo sudo[3242]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:28 motivo volumio[941]: info: Remote SSH Stopped Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:25:30 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:30 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:30 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:30 motivo volumio[941]: info: MyVolumio not started Dec 09 09:25:30 motivo volumio[941]: info: Initializing device activation check Dec 09 09:25:30 motivo volumio[941]: info: MyVolumio not started Dec 09 09:25:30 motivo volumio[941]: info: Initializing device activation check Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:25:30 motivo volumio[941]: info: Received Get System Info Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 09:25:30 motivo volumio[941]: info: Discovery: Getting this device information Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::volumioGetState Dec 09 09:25:30 motivo volumio[941]: info: CorePlayQueue::getTrack 0 Dec 09 09:25:30 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 09:25:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 09 09:25:39 motivo volumio[941]: info: MyVolumio login type: Token Dec 09 09:25:39 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 09:25:40 motivo volumio[941]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 09 09:25:40 motivo volumio[941]: info: MyVolumio token set successfully Dec 09 09:25:40 motivo volumio[941]: info: MYVOLUMIO: Adding device Dec 09 09:25:40 motivo volumio[941]: info: MYVOLUMIO: Evaluating Server Dec 09 09:25:41 motivo volumio[941]: info: MyVolumio Plan changed: premium Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 09 09:25:41 motivo volumio[941]: info: Removing browser output: myVolumio user plan is not superstar Dec 09 09:25:41 motivo volumio[941]: info: Removing audio output: Dec 09 09:25:41 motivo volumio[941]: info: MYVOLUMIO: Adding device Dec 09 09:25:41 motivo volumio[941]: info: MYVOLUMIO: Evaluating Server Dec 09 09:25:41 motivo volumio[941]: info: Remote config written successfully Dec 09 09:25:41 motivo volumio[941]: info: Starting Tunnel 1 Dec 09 09:25:41 motivo volumio[941]: info: Starting Tunnel Connection Checker Dec 09 09:25:41 motivo sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 09 09:25:41 motivo sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:41 motivo systemd[1]: Started MyVolumio SSH Tunnel. Dec 09 09:25:41 motivo sudo[3299]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:41 motivo autossh[3302]: port set to 0, monitoring disabled Dec 09 09:25:41 motivo autossh[3302]: starting ssh (count 1) Dec 09 09:25:41 motivo autossh[3302]: ssh child pid is 3305 Dec 09 09:25:41 motivo volumiossh-tunnel[3301]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Dec 09 09:25:41 motivo volumio[941]: info: MYVolumio Device enabled Dec 09 09:25:41 motivo volumio[941]: info: MyVolumio status changed Dec 09 09:25:41 motivo volumio[941]: info: Streaming services startup Dec 09 09:25:41 motivo volumio[941]: info: Starting Streaming Daemon Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 09 09:25:41 motivo volumio[941]: info: Setting Geolocation for MyVolumio to eu4 Dec 09 09:25:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 09:25:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 09:25:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 09:25:41 motivo sudo[3325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 09 09:25:41 motivo sudo[3325]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 09:25:41 motivo volumio[941]: info: Remote SSH Started Dec 09 09:25:41 motivo sudo[3325]: pam_unix(sudo:session): session closed for user root Dec 09 09:25:41 motivo volumio[941]: error: Cannot start Volumio Streaming Daemon Dec 09 09:25:41 motivo volumio[941]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 09 09:25:41 motivo volumio[941]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 09 09:25:41 motivo volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 09 09:25:41 motivo volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Dec 09 09:25:41 motivo volumio[941]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 09:25:41 motivo volumio[941]: Error: Unable to resolve or reject the same promise twice Dec 09 09:25:41 motivo volumio[941]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 09 09:25:41 motivo volumio[941]: at Array. (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5676) Dec 09 09:25:41 motivo volumio[941]: at Promise.reject (/volumio/node_modules/kew/kew.js:204:26) Dec 09 09:25:41 motivo volumio[941]: at nextTickCallback (/volumio/node_modules/kew/kew.js:49:11) Dec 09 09:25:41 motivo volumio[941]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 09 09:25:41 motivo volumio[941]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 09:25:42 motivo sudo[3336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 09:24 Dec 09 09:25:42 motivo sudo[3336]: 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="889fa4d0fff9f001a4a3cc827407782acb637da4" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 05 Dec 2025 01:36:50 PM CET" VOLUMIO_VERSION="3.882" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="783859eb04ebabbf97eff683917cab10"