-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-11-03 11:13:20 CET. -- Nov 03 11:12:06 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Nov 03 11:12:06 motivo volumio[1033]: info: Loading i18n strings for locale it Nov 03 11:12:06 motivo volumio[1033]: Updating browse sources language Nov 03 11:12:06 motivo volumio[1033]: Cannot find translation for source QOBUZ Nov 03 11:12:06 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 11:12:06 motivo volumio[1033]: Cannot find translation for source QOBUZ Nov 03 11:12:06 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Nov 03 11:12:06 motivo volumio[1033]: info: Setting timezone to Europe/Rome Nov 03 11:12:06 motivo sudo[4734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Nov 03 11:12:06 motivo sudo[4734]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:06 motivo sudo[4734]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:06 motivo sudo[4737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Nov 03 11:12:06 motivo sudo[4737]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:06 motivo sudo[4737]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:06 motivo sudo[4740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Nov 03 11:12:06 motivo sudo[4740]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:06 motivo sudo[4740]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:06 motivo sudo[4743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Nov 03 11:12:06 motivo sudo[4743]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:07 motivo sudo[4743]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:07 motivo volumio[1033]: info: Fetching Streaming Services browse cache Nov 03 11:12:08 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Nov 03 11:12:08 motivo volumio[1033]: info: Loading i18n strings for locale it Nov 03 11:12:08 motivo volumio[1033]: Updating browse sources language Nov 03 11:12:08 motivo volumio[1033]: Cannot find translation for source QOBUZ Nov 03 11:12:08 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 11:12:08 motivo volumio[1033]: Cannot find translation for source QOBUZ Nov 03 11:12:08 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Nov 03 11:12:08 motivo volumio[1033]: info: Setting timezone to Europe/Rome Nov 03 11:12:08 motivo sudo[4751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Nov 03 11:12:08 motivo sudo[4751]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:08 motivo sudo[4751]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:08 motivo sudo[4754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Nov 03 11:12:08 motivo sudo[4754]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:08 motivo sudo[4754]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:08 motivo sudo[4757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Nov 03 11:12:08 motivo sudo[4757]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:08 motivo sudo[4757]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:08 motivo sudo[4760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Nov 03 11:12:08 motivo sudo[4760]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:08 motivo sudo[4760]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:08 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 03 11:12:08 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 03 11:12:08 motivo sudo[4763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 03 11:12:08 motivo sudo[4763]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:09 motivo volumio[1033]: info: Fetching Streaming Services browse cache Nov 03 11:12:12 motivo sudo[4763]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:14 motivo volumio[1033]: 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: 7 Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 11:12:15 motivo volumio[1033]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 03 11:12:15 motivo volumio[1033]: info: Received Get System Version Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 11:12:15 motivo volumio[1033]: info: Received Get System Info Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 11:12:15 motivo volumio[1033]: info: Discovery: Getting this device information Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:15 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 11:12:17 motivo volumio[1033]: 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: 7 Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Nov 03 11:12:17 motivo volumio[1033]: No protocol specified Nov 03 11:12:17 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Nov 03 11:12:17 motivo volumio[1033]: info: Received Get System Info Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 11:12:17 motivo volumio[1033]: info: Discovery: Getting this device information Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 11:12:17 motivo volumio-remote-updater[731]: No test mode Nov 03 11:12:17 motivo volumio-remote-updater[731]: No alpha test mode Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:17 motivo volumio[1033]: info: Retrieving Cloud Streaming UI Nov 03 11:12:17 motivo volumio[1033]: info: Getting Tidal Cloud Configuration Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:17 motivo volumio[1033]: info: Getting Qobuz Cloud Configuration Nov 03 11:12:17 motivo volumio[1033]: info: Asking plugin for UI Config Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:17 motivo volumio[1033]: info: Getting Spotify Cloud Configuration Nov 03 11:12:17 motivo volumio[1033]: info: Asking plugin for UI Config Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:17 motivo volumio[1033]: info: Saving Spotify Acccount Nov 03 11:12:17 motivo volumio[1033]: info: Got it Nov 03 11:12:17 motivo volumio[1033]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 03 11:12:17 motivo volumio[1033]: info: Got Tidal Cloud Configuration Nov 03 11:12:17 motivo volumio[1033]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 11:12:17 motivo volumio[1033]: info: Got it Nov 03 11:12:17 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:18 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Nov 03 11:12:18 motivo volumio[1033]: info: Loading i18n strings for locale en Nov 03 11:12:18 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Nov 03 11:12:18 motivo volumio[1033]: info: Setting timezone to Europe/Rome Nov 03 11:12:18 motivo sudo[4849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Nov 03 11:12:18 motivo sudo[4849]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:18 motivo sudo[4849]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:18 motivo sudo[4852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Nov 03 11:12:18 motivo sudo[4852]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:18 motivo sudo[4852]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:18 motivo sudo[4855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Nov 03 11:12:18 motivo sudo[4855]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:18 motivo sudo[4855]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:18 motivo sudo[4858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Nov 03 11:12:18 motivo sudo[4858]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:18 motivo sudo[4858]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:18 motivo volumio[1033]: Updating browse sources language Nov 03 11:12:18 motivo volumio[1033]: Cannot find translation for source QOBUZ Nov 03 11:12:18 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 11:12:18 motivo volumio[1033]: Cannot find translation for source QOBUZ Nov 03 11:12:19 motivo volumio[1033]: info: Fetching Streaming Services browse cache Nov 03 11:12:20 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 03 11:12:20 motivo volumio[1033]: info: MyVolumio login type: Token Nov 03 11:12:20 motivo volumio[1033]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 03 11:12:21 motivo volumio[1033]: info: MyVolumio token set successfully Nov 03 11:12:21 motivo volumio[1033]: info: MYVOLUMIO: Adding device Nov 03 11:12:21 motivo volumio[1033]: info: MYVOLUMIO: Evaluating Server Nov 03 11:12:21 motivo volumio[1033]: info: Removing browser output: myVolumio user plan is not superstar Nov 03 11:12:21 motivo volumio[1033]: info: Removing audio output: Nov 03 11:12:21 motivo volumio[1033]: info: Stoppping Tunnel 1 Nov 03 11:12:21 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Nov 03 11:12:21 motivo sudo[4898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 03 11:12:21 motivo sudo[4898]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:21 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Nov 03 11:12:21 motivo sudo[4898]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:21 motivo volumio[1033]: No protocol specified Nov 03 11:12:21 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:21 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 11:12:21 motivo volumio[1033]: info: Remote SSH Stopped Nov 03 11:12:22 motivo volumio[1033]: info: Setting Geolocation for MyVolumio to eu4 Nov 03 11:12:22 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:22 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:22 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 03 11:12:23 motivo volumio[1033]: info: Preparing to save Alsa Options, stopping services first Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioPause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::stPlaybackTimer Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::servicePause Nov 03 11:12:23 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::servicePause Nov 03 11:12:23 motivo volumio[1033]: info: [1762164743196] ControllerQobuz::pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 11:12:23 motivo volumio[1033]: info: ControllerMpd::pause Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand pause Nov 03 11:12:23 motivo volumio[1033]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"Analog + I2S + Headphones"},"disallowPush":true} Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 11:12:23 motivo volumio[1033]: No protocol specified Nov 03 11:12:23 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:23 motivo volumio[1033]: No protocol specified Nov 03 11:12:23 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 11:12:23 motivo volumio[1033]: info: Found match in Cards Database: setting mixer for card Analog + I2S + Headphones Nov 03 11:12:23 motivo volumio[1033]: info: QobuzConnect: setDeactiveState invoked Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Nov 03 11:12:23 motivo vtcs[1759]: [2025-11-03 11:12:23.316] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Nov 03 11:12:23 motivo vtcs[1759]: [2025-11-03 11:12:23.317] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Nov 03 11:12:23 motivo sudo[4909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 11:12:23 motivo sudo[4909]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo systemd[1]: Stopping Volumio Tidal Connect Service... Nov 03 11:12:23 motivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 03 11:12:23 motivo systemd[1]: vtcs.service: Succeeded. Nov 03 11:12:23 motivo systemd[1]: Stopped Volumio Tidal Connect Service. Nov 03 11:12:23 motivo volumio[1033]: No protocol specified Nov 03 11:12:23 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:23 motivo volumio[1033]: info: Setting mixer PCM for card Analog + I2S + Headphones Nov 03 11:12:23 motivo volumio[1033]: info: QobuzConnect: setDeactiveState invoked Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Nov 03 11:12:23 motivo sudo[4909]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo sudo[4915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 11:12:23 motivo sudo[4915]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo sudo[4915]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo volumio[1033]: No protocol specified Nov 03 11:12:23 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:23 motivo volumio[1033]: info: Applying Volume Override Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 03 11:12:23 motivo volumio[1033]: info: Updating Volume Controller Parameters: Device: 5 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 03 11:12:23 motivo volumio[1033]: info: Enabling external Volume Control Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:23 motivo volumio[1033]: info: Preparing to generate the ALSA configuration file Nov 03 11:12:23 motivo sudo[4921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 11:12:23 motivo sudo[4921]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo sudo[4924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 11:12:23 motivo sudo[4924]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo sudo[4921]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo sudo[4924]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo sudo[4931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 11:12:23 motivo sudo[4931]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo sudo[4931]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo volumio[1033]: info: Updating MyVolumio device info Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:23 motivo sudo[4938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 11:12:23 motivo sudo[4938]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo volumio[1033]: info: Retrieving Cloud Streaming UI Nov 03 11:12:23 motivo sudo[4939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 11:12:23 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:23 motivo sudo[4939]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo volumio[1033]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 03 11:12:23 motivo volumio[1033]: info: Reading ALSA contributions from plugins. Nov 03 11:12:23 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 11:12:23 motivo qobuz-connect[1626]: 20251103 11:12:23.690 [1626.1626] INFO SampleApp: Stopping Local configuration server Nov 03 11:12:23 motivo sudo[4939]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo volumio[1033]: info: Nov 03 11:12:23 motivo volumio[1033]: ---------------------------- MPD announces state update: player Nov 03 11:12:23 motivo volumio[1033]: info: sendMpdCommand pause took 495 milliseconds Nov 03 11:12:23 motivo volumio[1033]: info: ControllerMpd::getState Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Nov 03 11:12:23 motivo volumio[1033]: info: Nov 03 11:12:23 motivo volumio[1033]: ---------------------------- MPD announces state update: player Nov 03 11:12:23 motivo volumio[1033]: info: ControllerMpd::getState Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Nov 03 11:12:23 motivo volumio[1033]: info: Nov 03 11:12:23 motivo volumio[1033]: ---------------------------- MPD announces state update: player Nov 03 11:12:23 motivo volumio[1033]: info: ControllerMpd::getState Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Nov 03 11:12:23 motivo qobuz-connect[1626]: 20251103 11:12:23.702 [1626.1626] INFO SampleApp: shat down connection on UNIX socket Nov 03 11:12:23 motivo sudo[4942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 11:12:23 motivo sudo[4942]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:23 motivo volumio[1033]: info: Signalling Playback active due to playback status change Nov 03 11:12:23 motivo systemd[1]: qobuz-connect.service: Succeeded. Nov 03 11:12:23 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 11:12:23 motivo volumio[1033]: info: Signalling Playback active due to playback status change Nov 03 11:12:23 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:23 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:23 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:23 motivo systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 11:12:23 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:23 motivo volumio[1033]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Nov 03 11:12:23 motivo sudo[4938]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo volumio[1033]: info: sendMpdCommand status took 37 milliseconds Nov 03 11:12:23 motivo volumio[1033]: info: sendMpdCommand status took 35 milliseconds Nov 03 11:12:23 motivo volumio[1033]: info: sendMpdCommand status took 32 milliseconds Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::parseState Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::parseState Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::parseState Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 11:12:23 motivo volumio[1033]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 03 11:12:23 motivo volumio[1033]: info: QobuzConnect: setDeactiveState invoked Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 11:12:23 motivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Nov 03 11:12:23 motivo systemd[1]: qobuz-connect.service: Succeeded. Nov 03 11:12:23 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 11:12:23 motivo volumio[1033]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 03 11:12:23 motivo volumio[1033]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 03 11:12:23 motivo volumio[1033]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::parseTrackInfo Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::parseTrackInfo Nov 03 11:12:23 motivo volumio[1033]: verbose: ControllerMpd::parseTrackInfo Nov 03 11:12:23 motivo volumio[1033]: info: ControllerMpd::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::servicePushState Nov 03 11:12:23 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:23 motivo volumio[1033]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108953,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"797 Kbps","isStreaming":false,"title":"46456010","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46456010","trackType":"qobuz"} Nov 03 11:12:23 motivo volumio[1033]: verbose: CURRENT POSITION 0 Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::syncState stateService pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::syncState currentStatus pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:23 motivo systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 11:12:23 motivo sudo[4942]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::stPlaybackTimer Nov 03 11:12:23 motivo volumio[1033]: info: ControllerMpd::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::servicePushState Nov 03 11:12:23 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:23 motivo volumio[1033]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108953,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"797 Kbps","isStreaming":false,"title":"46456010","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46456010","trackType":"qobuz"} Nov 03 11:12:23 motivo volumio[1033]: verbose: CURRENT POSITION 0 Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::syncState stateService pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::syncState currentStatus pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::stPlaybackTimer Nov 03 11:12:23 motivo volumio[1033]: info: ControllerMpd::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::servicePushState Nov 03 11:12:23 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:23 motivo volumio[1033]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108953,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"797 Kbps","isStreaming":false,"title":"46456010","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46456010","trackType":"qobuz"} Nov 03 11:12:23 motivo volumio[1033]: verbose: CURRENT POSITION 0 Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::syncState stateService pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::syncState currentStatus pause Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:23 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:23 motivo volumio[1033]: info: CoreStateMachine::stPlaybackTimer Nov 03 11:12:23 motivo volumio[1033]: info: ------------------------------ 100ms Nov 03 11:12:23 motivo volumio[1033]: info: ------------------------------ 94ms Nov 03 11:12:23 motivo volumio[1033]: info: ------------------------------ 92ms Nov 03 11:12:23 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:23 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:23 motivo volumio[1033]: No protocol specified Nov 03 11:12:23 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 11:12:23 motivo volumio[1033]: No protocol specified Nov 03 11:12:23 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Nov 03 11:12:23 motivo volumio[1033]: info: Getting Tidal Cloud Configuration Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:23 motivo volumio[1033]: info: Getting Qobuz Cloud Configuration Nov 03 11:12:23 motivo volumio[1033]: info: Asking plugin for UI Config Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:23 motivo volumio[1033]: info: Getting Spotify Cloud Configuration Nov 03 11:12:23 motivo volumio[1033]: info: Asking plugin for UI Config Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:23 motivo volumio[1033]: info: Saving Spotify Acccount Nov 03 11:12:23 motivo volumio[1033]: info: Got it Nov 03 11:12:23 motivo volumio[1033]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 03 11:12:23 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:23 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:23 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: Not Reporting Auto name since its the default one Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: Not Reporting Auto name since its the default one Nov 03 11:12:23 motivo volumio[1033]: info: Executing endpoint qc_getconfig Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.980 [4946.4946] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 11:12:23 motivo volumio[1033]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 11:12:23 motivo volumio[1033]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.984 [4946.4946] INFO VolumeManager: [0x235b958]: Setting new playback volume: 75 Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.984 [4946.4946] INFO VolumeManager: [0x235b958]: Setting new mute state: 0 Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.984 [4946.4946] INFO QobuzConnect: [0x235c328]: Client initialized! Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.984 [4946.4946] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.991 [4946.4946] INFO LocalConfigManager: [0x235b238]: Starting Local Configuration server Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.991 [4946.4946] INFO SampleApp: Starting Local configuration server Nov 03 11:12:23 motivo qobuz-connect[4946]: 20251103 11:12:23.992 [4946.4946] INFO SampleApp: Connected to UNIX socket client 0x2350f60 Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 03 11:12:24 motivo volumio[1033]: No protocol specified Nov 03 11:12:24 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 03 11:12:24 motivo sudo[4966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 03 11:12:24 motivo sudo[4966]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[4966]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo volumio[1033]: No protocol specified Nov 03 11:12:24 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:24 motivo sudo[4970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 03 11:12:24 motivo sudo[4970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo volumio[1033]: info: Asound.conf file unchanged, so no further update is needed Nov 03 11:12:24 motivo volumio[1033]: info: Output device has changed, restarting MPD Nov 03 11:12:24 motivo qobuz-connect[4946]: 20251103 11:12:24.130 [4946.4946] INFO SampleApp: Playback volume changed: 75 Nov 03 11:12:24 motivo systemd[1]: Stopping RAAT DAEMON... Nov 03 11:12:24 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 11:12:24 motivo systemd[1]: raat-daemon.service: Succeeded. Nov 03 11:12:24 motivo systemd[1]: Stopped RAAT DAEMON. Nov 03 11:12:24 motivo sudo[4973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 03 11:12:24 motivo sudo[4973]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo systemd[1]: Started RAAT DAEMON. Nov 03 11:12:24 motivo sudo[4973]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo sudo[4970]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo sudo[4979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 11:12:24 motivo sudo[4985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 11:12:24 motivo sudo[4985]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[4979]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[4987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 03 11:12:24 motivo sudo[4987]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo volumio[1033]: info: Output device has changed, restarting Shairport Sync Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:24 motivo systemd[1]: Stopping Music Player Daemon... Nov 03 11:12:24 motivo systemd[1]: Stopping RAAT DAEMON... Nov 03 11:12:24 motivo sudo[4979]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 11:12:24 motivo systemd[1]: raat-daemon.service: Succeeded. Nov 03 11:12:24 motivo systemd[1]: Stopped RAAT DAEMON. Nov 03 11:12:24 motivo volumio[1033]: No protocol specified Nov 03 11:12:24 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:24 motivo volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 11:12:24 motivo volumio[1033]: No protocol specified Nov 03 11:12:24 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:24 motivo volumio[1033]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo systemd[1]: Started RAAT DAEMON. Nov 03 11:12:24 motivo systemd[1]: mpd.service: Succeeded. Nov 03 11:12:24 motivo systemd[1]: Stopped Music Player Daemon. Nov 03 11:12:24 motivo sudo[4987]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo systemd[1]: Starting Music Player Daemon... Nov 03 11:12:24 motivo volumio[1033]: info: QobuzConnect: setDeactiveState invoked Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:24 motivo volumio[1033]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo sudo[5012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 11:12:24 motivo sudo[5012]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[5005]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 11:12:24 motivo sudo[5016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 11:12:24 motivo sudo[5005]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[5016]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[5012]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo sudo[5005]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo volumio[1033]: info: Got Tidal Cloud Configuration Nov 03 11:12:24 motivo volumio[1033]: info: Got it Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:24 motivo sudo[5025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:24 motivo sudo[5025]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[5016]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:24 motivo sudo[5025]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:24 motivo volumio[1033]: info: MPD Permissions set Nov 03 11:12:24 motivo volumio[1033]: info: Raat Daemon started successfully Nov 03 11:12:24 motivo volumio[1033]: info: Raat Daemon started successfully Nov 03 11:12:24 motivo volumio[1033]: info: MCU Signalled Playback Inactive Nov 03 11:12:24 motivo sudo[5028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 11:12:24 motivo sudo[5028]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo volumio[1033]: error: Upnp client error: Error: This socket has been ended by the other party Nov 03 11:12:24 motivo volumio[1033]: info: Executing endpoint restartRAATSocket Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 03 11:12:24 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 11:12:24 motivo qobuz-connect[4946]: 20251103 11:12:24.483 [4946.4946] INFO SampleApp: Stopping Local configuration server Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo qobuz-connect[4946]: 20251103 11:12:24.493 [4946.4946] INFO SampleApp: shat down connection on UNIX socket Nov 03 11:12:24 motivo systemd[1]: qobuz-connect.service: Succeeded. Nov 03 11:12:24 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:24 motivo volumio[1033]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 03 11:12:24 motivo volumio[1033]: info: QobuzConnect: setDeactiveState invoked Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:24 motivo systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 11:12:24 motivo volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 03 11:12:24 motivo sudo[5028]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo volumio[1033]: info: Starting Shairport Sync Nov 03 11:12:24 motivo sudo[5041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 11:12:24 motivo sudo[5041]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo volumio[1033]: info: Executing endpoint qc_getconfig Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.612 [5031.5031] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 11:12:24 motivo volumio[1033]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 11:12:24 motivo volumio[1033]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.619 [5031.5031] INFO VolumeManager: [0x1c37958]: Setting new playback volume: 75 Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.620 [5031.5031] INFO VolumeManager: [0x1c37958]: Setting new mute state: 0 Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.620 [5031.5031] INFO QobuzConnect: [0x1c38328]: Client initialized! Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.620 [5031.5031] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Nov 03 11:12:24 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 11:12:24 motivo systemd[1]: shairport-sync.service: Succeeded. Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:24 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 11:12:24 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.652 [5031.5031] INFO LocalConfigManager: [0x1c37238]: Starting Local Configuration server Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.652 [5031.5031] INFO SampleApp: Starting Local configuration server Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.653 [5031.5031] INFO SampleApp: Connected to UNIX socket client 0x1c2cf60 Nov 03 11:12:24 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 11:12:24 motivo sudo[5041]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo volumio[1033]: info: Shairport-Sync Started Nov 03 11:12:24 motivo qobuz-connect[5031]: 20251103 11:12:24.761 [5031.5031] INFO SampleApp: Playback volume changed: 75 Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 11:12:24 motivo volumio[1033]: info: Not Reporting Auto name since its the default one Nov 03 11:12:24 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 03 11:12:24 motivo volumio[1033]: No protocol specified Nov 03 11:12:24 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:24 motivo sudo[5054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 03 11:12:24 motivo sudo[5054]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo sudo[5054]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:24 motivo sudo[5056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 03 11:12:24 motivo sudo[5056]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:24 motivo systemd[1]: Stopping RAAT DAEMON... Nov 03 11:12:25 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 11:12:25 motivo systemd[1]: raat-daemon.service: Succeeded. Nov 03 11:12:25 motivo systemd[1]: Stopped RAAT DAEMON. Nov 03 11:12:25 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 11:12:25 motivo volumio[1033]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 11:12:25 motivo systemd[1]: Started RAAT DAEMON. Nov 03 11:12:25 motivo sudo[5056]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:25 motivo volumio[1033]: info: Raat Daemon started successfully Nov 03 11:12:25 motivo volumio[1033]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 03 11:12:25 motivo volumio[1033]: info: Executing endpoint restartRAATSocket Nov 03 11:12:25 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 03 11:12:25 motivo volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 03 11:12:25 motivo mpd[5023]: Nov 03 11:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 03 11:12:25 motivo systemd[1]: Started Music Player Daemon. Nov 03 11:12:25 motivo sudo[4985]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:25 motivo volumio[1033]: error: updateQueue error: null Nov 03 11:12:26 motivo volumio[1033]: info: TidalConnect service stoped! Nov 03 11:12:26 motivo volumio[1033]: info: TidalConnect service stoped! Nov 03 11:12:26 motivo volumio[1033]: info: TidalConnect service stoped! Nov 03 11:12:26 motivo volumio[1033]: info: TidalConnect service stoped! Nov 03 11:12:26 motivo volumio[1033]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 03 11:12:26 motivo volumio[1033]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 03 11:12:26 motivo volumio[1033]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 03 11:12:26 motivo volumio[1033]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 03 11:12:26 motivo sudo[5090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 03 11:12:26 motivo sudo[5090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:26 motivo sudo[5093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 03 11:12:26 motivo sudo[5093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:26 motivo systemd[1]: Started Volumio Tidal Connect Service. Nov 03 11:12:26 motivo sudo[5090]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:26 motivo sudo[5093]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:26 motivo volumio[1033]: info: Executing endpoint tc_getconfig Nov 03 11:12:26 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 03 11:12:26 motivo vtcs[5095]: STARTING TidalConnect services, version: 1.5.1-beta1 Nov 03 11:12:26 motivo vtcs[5095]: STARTED TidalConnect services. Nov 03 11:12:26 motivo volumio[1033]: info: Executing endpoint tc_connect Nov 03 11:12:26 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 03 11:12:26 motivo volumio[1033]: info: Connecting to TidalConnect Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::servicePushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:27 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:27 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:27 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:27 motivo volumio[1033]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::servicePushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:27 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:27 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:27 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:27 motivo volumio[1033]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 03 11:12:27 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:27 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:27 motivo volumio[1033]: info: RAAT: Requesting Headphone Status Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 03 11:12:27 motivo volumio[1033]: info: MCU Signalled Headphone Mode Disabled Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Nov 03 11:12:27 motivo volumio[1033]: info: Reporting Headphone State: false Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:27 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:27 motivo volumio[1033]: info: RAAT: Requesting Headphone Status Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 03 11:12:27 motivo volumio[1033]: info: MCU Signalled Headphone Mode Disabled Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Nov 03 11:12:27 motivo volumio[1033]: info: Reporting Headphone State: false Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:27 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:27 motivo volumio[1033]: info: MYVOLUMIO: Adding device Nov 03 11:12:27 motivo volumio[1033]: info: MYVOLUMIO: Evaluating Server Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 03 11:12:27 motivo volumio[1033]: info: CoreStateMachine::getcurrentVolume Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Nov 03 11:12:27 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:27 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:27 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:27 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:27 motivo volumio[1033]: info: TidalConnect service stoped! Nov 03 11:12:27 motivo volumio[1033]: info: TidalConnect service stoped! Nov 03 11:12:27 motivo volumio[1033]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 03 11:12:27 motivo volumio[1033]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 03 11:12:27 motivo sudo[5139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 03 11:12:27 motivo sudo[5139]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:12:27 motivo sudo[5139]: pam_unix(sudo:session): session closed for user root Nov 03 11:12:27 motivo volumio[1033]: info: RAAT: Requesting Headphone Status Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 03 11:12:27 motivo volumio[1033]: info: MCU Signalled Headphone Mode Disabled Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Nov 03 11:12:27 motivo volumio[1033]: info: Reporting Headphone State: false Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:27 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:27 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:28 motivo volumio[1033]: info: Setting Geolocation for MyVolumio to eu6 Nov 03 11:12:28 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:28 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:28 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:28 motivo volumio[1033]: error: Upnp client error: Error: This socket has been ended by the other party Nov 03 11:12:28 motivo volumio[1033]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 03 11:12:29 motivo volumio[1033]: info: Updating MyVolumio device info Nov 03 11:12:29 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 11:12:29 motivo volumio[1033]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 03 11:12:29 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Nov 03 11:12:29 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 11:12:29 motivo volumio-remote-updater[731]: No test mode Nov 03 11:12:29 motivo volumio-remote-updater[731]: No alpha test mode Nov 03 11:12:29 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 03 11:12:29 motivo volumio[1033]: info: TidalConnect service started! Nov 03 11:12:29 motivo volumio[1033]: info: TidalConnect service started! Nov 03 11:12:29 motivo volumio[1033]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 11:12:29 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 11:12:30 motivo volumio[1033]: info: TidalConnect service started! Nov 03 11:12:31 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Nov 03 11:12:31 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Nov 03 11:12:31 motivo volumio[1033]: No protocol specified Nov 03 11:12:31 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:31 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Nov 03 11:12:31 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Nov 03 11:12:31 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Nov 03 11:12:31 motivo volumio[1033]: No protocol specified Nov 03 11:12:31 motivo volumio[1033]: xcb_connection_has_error() returned true Nov 03 11:12:35 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Nov 03 11:12:35 motivo volumio[1033]: info: CoreCommandRouter::Reload Ui Nov 03 11:12:35 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Nov 03 11:12:35 motivo volumio[1033]: info: Wizard terminated Successfully Nov 03 11:12:35 motivo volumio[1033]: info: CoreCommandRouter::volumiosetStartupVolume Nov 03 11:12:35 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 11:12:37 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Nov 03 11:12:37 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 11:12:37 motivo volumio-remote-updater[731]: No test mode Nov 03 11:12:37 motivo volumio-remote-updater[731]: No alpha test mode Nov 03 11:12:37 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 03 11:12:37 motivo volumio[1033]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 11:12:37 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 11:12:37 motivo volumio[1033]: 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: 6 Nov 03 11:12:38 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Nov 03 11:12:38 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 11:12:38 motivo volumio-remote-updater[731]: No test mode Nov 03 11:12:38 motivo volumio-remote-updater[731]: No alpha test mode Nov 03 11:12:38 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 03 11:12:39 motivo volumio[1033]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 11:12:39 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 11:12:39 motivo volumio[1033]: 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: 7 Nov 03 11:12:39 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 11:12:40 motivo volumio[1033]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 03 11:12:40 motivo volumio[1033]: info: Received Get System Info Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 11:12:40 motivo volumio[1033]: info: Discovery: Getting this device information Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:40 motivo volumio[1033]: info: Listing playlists Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 11:12:40 motivo volumio[1033]: info: Received Get System Info Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 11:12:40 motivo volumio[1033]: info: Discovery: Getting this device information Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:40 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 11:12:41 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 11:12:42 motivo volumio[1033]: info: Received Get System Info Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 11:12:42 motivo volumio[1033]: info: Discovery: Getting this device information Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 11:12:42 motivo volumio[1033]: info: Executing endpoint metavolumio Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 03 11:12:42 motivo volumio[1033]: info: Executing endpoint metavolumio Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 03 11:12:42 motivo volumio[1033]: info: Executing endpoint metavolumio Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 03 11:12:42 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Nov 03 11:12:47 motivo volumio[1033]: info: CoreCommandRouter::volumioPlay Nov 03 11:12:47 motivo volumio[1033]: info: CoreStateMachine::play index undefined Nov 03 11:12:47 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 11:12:47 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:47 motivo volumio[1033]: info: CoreStateMachine::startPlaybackTimer Nov 03 11:12:47 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:47 motivo volumio[1033]: info: [1762164767673] ControllerQobuz::resume Nov 03 11:12:47 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 11:12:47 motivo volumio[1033]: info: ControllerMpd::resume Nov 03 11:12:47 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Nov 03 11:12:47 motivo volumio[1033]: info: sendMpdCommand play took 2 milliseconds Nov 03 11:12:48 motivo volumio[1033]: info: CoreCommandRouter::volumioPlay Nov 03 11:12:48 motivo volumio[1033]: info: CoreStateMachine::play index undefined Nov 03 11:12:48 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 11:12:48 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:48 motivo volumio[1033]: info: CoreStateMachine::startPlaybackTimer Nov 03 11:12:48 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:48 motivo volumio[1033]: info: [1762164768475] ControllerQobuz::resume Nov 03 11:12:48 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 11:12:48 motivo volumio[1033]: info: ControllerMpd::resume Nov 03 11:12:48 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Nov 03 11:12:48 motivo volumio[1033]: info: sendMpdCommand play took 1 milliseconds Nov 03 11:12:48 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Nov 03 11:12:48 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 11:12:48 motivo systemd[1]: systemd-timedated.service: Succeeded. Nov 03 11:12:49 motivo volumio-remote-updater[731]: No test mode Nov 03 11:12:49 motivo volumio-remote-updater[731]: No alpha test mode Nov 03 11:12:49 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 03 11:12:49 motivo volumio[1033]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 11:12:49 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 11:12:49 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 03 11:12:50 motivo volumio[1033]: info: CoreCommandRouter::volumioGetQueue Nov 03 11:12:50 motivo volumio[1033]: info: CoreStateMachine::getQueue Nov 03 11:12:50 motivo volumio[1033]: info: CorePlayQueue::getQueue Nov 03 11:12:52 motivo volumio[1033]: info: CoreCommandRouter::volumioPlay Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::play index 1 Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::stop Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::updateTrackBlock Nov 03 11:12:52 motivo volumio[1033]: info: CorePlayQueue::getTrackBlock Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::stPlaybackTimer Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:52 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:52 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 11:12:52 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:52 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:52 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:52 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:52 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::serviceStop Nov 03 11:12:52 motivo volumio[1033]: info: CorePlayQueue::getTrack 0 Nov 03 11:12:52 motivo volumio[1033]: info: CoreCommandRouter::serviceStop Nov 03 11:12:52 motivo volumio[1033]: info: [1762164772561] ControllerQobuz::stop Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 11:12:52 motivo volumio[1033]: info: ControllerMpd::stop Nov 03 11:12:52 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 11:12:52 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:52 motivo volumio[1033]: info: sendMpdCommand stop took 18 milliseconds Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::play index undefined Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 11:12:52 motivo volumio[1033]: info: CorePlayQueue::getTrack 1 Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::startPlaybackTimer Nov 03 11:12:52 motivo volumio[1033]: info: CorePlayQueue::getTrack 1 Nov 03 11:12:52 motivo volumio[1033]: info: [1762164772584] ControllerQobuz::clearAddPlayTrack Nov 03 11:12:52 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 11:12:52 motivo volumio[1033]: info: sendMpdCommand stop took 1 milliseconds Nov 03 11:12:52 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 11:12:52 motivo volumio[1033]: info: Nov 03 11:12:52 motivo volumio[1033]: ---------------------------- MPD announces system playlist update Nov 03 11:12:52 motivo volumio[1033]: info: Ignoring MPD Status Update Nov 03 11:12:52 motivo volumio[1033]: info: sendMpdCommand clear took 2 milliseconds Nov 03 11:12:52 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/46456011" Nov 03 11:12:52 motivo volumio[1033]: error: updateQueue error: null Nov 03 11:12:52 motivo volumio[1033]: info: ------------------------------ 5ms Nov 03 11:12:52 motivo volumio[1033]: STREAMING PROXY: Handling url /?data=qobuz://song/46456011 Nov 03 11:12:52 motivo volumio[1033]: info: Executing endpoint getStreamUrlqobuz Nov 03 11:12:52 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 03 11:12:52 motivo volumio[1033]: info: getStreamUrl took 181 milliseconds Nov 03 11:12:52 motivo volumio[1033]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3673199&eid=46456011&fmt=6&profile=raw&app_id=539451548&cid=3295270&etsp=1762168372&hmac=R6PDouitYkivfq5dHuRCTS1ZgIE Nov 03 11:12:52 motivo volumio[1033]: STREAMING PROXY: Response: 200, length: 30845684 Nov 03 11:12:52 motivo volumio[1033]: STREAMING PROXY: Client dropped request, destroying Nov 03 11:12:52 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/46456011" Nov 03 11:12:52 motivo volumio[1033]: info: Nov 03 11:12:52 motivo volumio[1033]: ---------------------------- MPD announces system playlist update Nov 03 11:12:52 motivo volumio[1033]: info: Ignoring MPD Status Update Nov 03 11:12:52 motivo volumio[1033]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/46456011" took 8 milliseconds Nov 03 11:12:52 motivo volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 11:12:52 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Nov 03 11:12:52 motivo volumio[1033]: info: ------------------------------ 9ms Nov 03 11:12:52 motivo volumio[1033]: info: sendMpdCommand play took 3 milliseconds Nov 03 11:12:52 motivo volumio[1033]: STREAMING PROXY: Handling url /?data=qobuz://song/46456011 Nov 03 11:12:52 motivo volumio[1033]: info: Executing endpoint getStreamUrlqobuz Nov 03 11:12:52 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 03 11:12:53 motivo volumio[1033]: info: getStreamUrl took 250 milliseconds Nov 03 11:12:53 motivo volumio[1033]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3673199&eid=46456011&fmt=6&profile=raw&app_id=539451548&cid=3295270&etsp=1762168373&hmac=fQIKkMPtqri_1JL1pRdszBwm8Ws Nov 03 11:12:53 motivo volumio[1033]: STREAMING PROXY: Response: 200, length: 30845684 Nov 03 11:12:53 motivo volumio[1033]: info: Nov 03 11:12:53 motivo volumio[1033]: ---------------------------- MPD announces state update: player Nov 03 11:12:53 motivo volumio[1033]: info: ControllerMpd::getState Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Nov 03 11:12:53 motivo volumio[1033]: info: Nov 03 11:12:53 motivo volumio[1033]: ---------------------------- MPD announces state update: player Nov 03 11:12:53 motivo volumio[1033]: info: sendMpdCommand status took 21 milliseconds Nov 03 11:12:53 motivo volumio[1033]: info: ControllerMpd::getState Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::parseState Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 11:12:53 motivo volumio[1033]: info: sendMpdCommand status took 4 milliseconds Nov 03 11:12:53 motivo volumio[1033]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::parseState Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::parseTrackInfo Nov 03 11:12:53 motivo volumio[1033]: info: ControllerMpd::pushState Nov 03 11:12:53 motivo volumio[1033]: info: CoreCommandRouter::servicePushState Nov 03 11:12:53 motivo volumio[1033]: info: CorePlayQueue::getTrack 1 Nov 03 11:12:53 motivo volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"255 Kbps","isStreaming":false,"title":"46456011","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46456011","trackType":"qobuz"} Nov 03 11:12:53 motivo volumio[1033]: verbose: CURRENT POSITION 1 Nov 03 11:12:53 motivo volumio[1033]: info: CoreStateMachine::syncState stateService play Nov 03 11:12:53 motivo volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Nov 03 11:12:53 motivo volumio[1033]: info: ------------------------------ 34ms Nov 03 11:12:53 motivo volumio[1033]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 03 11:12:53 motivo volumio[1033]: verbose: ControllerMpd::parseTrackInfo Nov 03 11:12:53 motivo volumio[1033]: info: ControllerMpd::pushState Nov 03 11:12:53 motivo volumio[1033]: info: CoreCommandRouter::servicePushState Nov 03 11:12:53 motivo volumio[1033]: info: CorePlayQueue::getTrack 1 Nov 03 11:12:53 motivo volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"425 Kbps","isStreaming":false,"title":"46456011","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/46456011","trackType":"qobuz"} Nov 03 11:12:53 motivo volumio[1033]: verbose: CURRENT POSITION 1 Nov 03 11:12:53 motivo volumio[1033]: info: CoreStateMachine::syncState stateService play Nov 03 11:12:53 motivo volumio[1033]: info: CoreStateMachine::syncState currentStatus play Nov 03 11:12:53 motivo volumio[1033]: info: Received an update from plugin. extracting info from payload Nov 03 11:12:53 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:53 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 11:12:53 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:53 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:53 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:53 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:53 motivo volumio[1033]: info: CoreStateMachine::pushState Nov 03 11:12:53 motivo volumio[1033]: info: CoreCommandRouter::volumioPushState Nov 03 11:12:53 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:53 motivo volumio[1033]: info: MRS: Pushing multiroomSync output update for this device Nov 03 11:12:53 motivo volumio[1033]: info: MRS: Pushing multiroomSync output Nov 03 11:12:53 motivo volumio[1033]: info: ------------------------------ 53ms Nov 03 11:12:53 motivo volumio[1033]: info: Signalling Playback active due to playback status change Nov 03 11:12:53 motivo volumio[1033]: info: Signalling Playback active due to playback status change Nov 03 11:12:53 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:53 motivo volumio[1033]: info: Updating RAAT Signal Path Nov 03 11:12:53 motivo volumio[1033]: info: MCU Signalled Playback Active Nov 03 11:12:55 motivo volumio[1033]: STREAMING PROXY: Client dropped request, destroying Nov 03 11:12:57 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 11:12:57 motivo volumio[1033]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 03 11:12:57 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 03 11:12:57 motivo volumio[1033]: info: Received Get System Version Nov 03 11:12:57 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 11:12:57 motivo volumio[1033]: info: Received Get System Info Nov 03 11:12:57 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 11:12:57 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 11:12:57 motivo volumio[1033]: info: Discovery: Getting this device information Nov 03 11:12:57 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:12:57 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 11:13:02 motivo volumio[1033]: info: CoreCommandRouter::volumioGetState Nov 03 11:13:04 motivo volumio[1033]: info: Executing endpoint metavolumio Nov 03 11:13:04 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 03 11:13:04 motivo volumio[1033]: info: Executing endpoint metavolumio Nov 03 11:13:04 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 03 11:13:04 motivo volumio[1033]: info: Executing endpoint metavolumio Nov 03 11:13:04 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 03 11:13:19 motivo volumio[1033]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Nov 03 11:13:19 motivo volumio[1033]: info: MRS: Starting cast device: LS60-Wireless-7b3846501cc750d90b6396d8c0d59903 Nov 03 11:13:19 motivo volumio[1033]: info: Enabled audio output: LS60-Wireless-7b3846501cc750d90b6396d8c0d59903 Nov 03 11:13:19 motivo volumio[1033]: info: MRS: Setting this device as Streaming Server Nov 03 11:13:19 motivo volumio[1033]: info: Nov 03 11:13:19 motivo volumio[1033]: [1762164799476] ---------------------------- MRS: Setting Streaming Server Nov 03 11:13:19 motivo volumio[1033]: info: MRS: enable multiroom server output Nov 03 11:13:19 motivo volumio[1033]: info: MRS: Set multiroom target PCM to volumioMultiRoom Nov 03 11:13:19 motivo volumio[1033]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Nov 03 11:13:19 motivo volumio[1033]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 03 11:13:19 motivo volumio[1033]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Nov 03 11:13:19 motivo volumio[1033]: info: MRS: STARTING volumioStreaming Nov 03 11:13:19 motivo sudo[5296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 03 11:13:19 motivo sudo[5296]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:13:19 motivo sudo[5296]: pam_unix(sudo:session): session closed for user root Nov 03 11:13:19 motivo sudo[5298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Nov 03 11:13:19 motivo sudo[5298]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:13:19 motivo systemd[1]: Started VolumioStreamingService. Nov 03 11:13:19 motivo sudo[5298]: pam_unix(sudo:session): session closed for user root Nov 03 11:13:19 motivo volumio[1033]: info: MRS: volumioStreaming STARTED Nov 03 11:13:19 motivo sudo[5302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 03 11:13:19 motivo sudo[5302]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 11:13:19 motivo sudo[5302]: pam_unix(sudo:session): session closed for user root Nov 03 11:13:19 motivo volumio[1033]: info: MRS: Connected to cast device: LS60-Wireless-7b3846501cc750d90b6396d8c0d59903 Nov 03 11:13:19 motivo volumio[1033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 11:13:19 motivo volumio[1033]: TypeError: Cannot read property 'on' of undefined Nov 03 11:13:19 motivo volumio[1033]: at /volumio/app/plugins/audio_interface/multiroom/multiroom_real:1:29834 Nov 03 11:13:19 motivo volumio[1033]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Nov 03 11:13:19 motivo volumio[1033]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Nov 03 11:13:19 motivo volumio[1033]: at fn.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Nov 03 11:13:19 motivo volumio[1033]: at fn.emit (events.js:327:22) Nov 03 11:13:19 motivo volumio[1033]: at Channel.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Nov 03 11:13:19 motivo volumio[1033]: at Channel.emit (events.js:315:20) Nov 03 11:13:19 motivo volumio[1033]: at Client.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Nov 03 11:13:19 motivo volumio[1033]: at Client.emit (events.js:327:22) Nov 03 11:13:19 motivo volumio[1033]: at PacketStreamWrapper.onpacket (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Nov 03 11:13:19 motivo volumio[1033]: at PacketStreamWrapper.emit (events.js:315:20) Nov 03 11:13:19 motivo volumio[1033]: at TLSSocket. (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Nov 03 11:13:19 motivo volumio[1033]: at TLSSocket.emit (events.js:315:20) Nov 03 11:13:19 motivo volumio[1033]: at emitReadable_ (internal/streams/readable.js:569:12) Nov 03 11:13:19 motivo volumio[1033]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Nov 03 11:13:19 motivo volumio[1033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 11:13:20 motivo sudo[5314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 11:12 Nov 03 11:13:20 motivo sudo[5314]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 12:42:34 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="03b8c851b751de445da30842a70a04d1"