-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Thu 2025-06-19 07:12:13 CST. -- Jun 19 07:11:00 volumio systemd[1]: Started Music Player Daemon. Jun 19 07:11:00 volumio sudo[2740]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:00 volumio volumio[1098]: error: updateQueue error: null Jun 19 07:11:04 volumio volumio[1098]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 19 07:11:04 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 19 07:11:04 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:04 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:04 volumio volumio[1098]: info: Restoring Previous Volume level: false false Jun 19 07:11:04 volumio volumio[1098]: info: Volume configurations have been set Jun 19 07:11:04 volumio volumio[1098]: info: QobuzConnect: setDeactiveState invoked Jun 19 07:11:04 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:04 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:04 volumio vtcs[2470]: [2025-06-19 07:11:04.940] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 19 07:11:04 volumio vtcs[2470]: [2025-06-19 07:11:04.940] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 19 07:11:04 volumio vtcs[2470]: [2025-06-19 07:11:04.941] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 19 07:11:04 volumio sudo[2778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 19 07:11:04 volumio sudo[2778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:05 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jun 19 07:11:05 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 19 07:11:05 volumio systemd[1]: vtcs.service: Succeeded. Jun 19 07:11:05 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jun 19 07:11:05 volumio volumio[1098]: No protocol specified Jun 19 07:11:05 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:05 volumio sudo[2778]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 19 07:11:05 volumio volumio[1098]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 19 07:11:05 volumio volumio[1098]: info: Disabling external Volume Control Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:05 volumio sudo[2796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 19 07:11:05 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:05 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:05 volumio sudo[2796]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:05 volumio sudo[2806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 19 07:11:05 volumio volumio[1098]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 19 07:11:05 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:05 volumio sudo[2806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:05 volumio sudo[2806]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:05 volumio sudo[2820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 19 07:11:05 volumio sudo[2820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:05 volumio volumio[1098]: No protocol specified Jun 19 07:11:05 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:05 volumio qobuz-connect[2385]: 20250619 07:11:05.229 [2385.2385] INFO SampleApp: Stopping Local configuration server Jun 19 07:11:05 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 07:11:05 volumio qobuz-connect[2385]: 20250619 07:11:05.239 [2385.2385] INFO SampleApp: shat down connection on UNIX socket Jun 19 07:11:05 volumio systemd[1]: qobuz-connect.service: Succeeded. Jun 19 07:11:05 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 19 07:11:05 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jun 19 07:11:05 volumio sudo[2820]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:05 volumio volumio[1098]: No protocol specified Jun 19 07:11:05 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 07:11:05 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:05 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:11:05 volumio volumio[1098]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 19 07:11:05 volumio volumio[1098]: info: QobuzConnect: setDeactiveState invoked Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:05 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:05 volumio volumio[1098]: info: VolumeController:: Volume=undefined Mute =false Jun 19 07:11:05 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:05 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:05 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:05 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:05 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:05 volumio volumio[1098]: info: Executing endpoint qc_getconfig Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.354 [2833.2833] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 19 07:11:05 volumio volumio[1098]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 19 07:11:05 volumio volumio[1098]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.358 [2833.2833] INFO VolumeManager: [0x7feaa408]: Setting new playback volume: 75 Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.358 [2833.2833] INFO VolumeManager: [0x7feaa408]: Setting new mute state: 0 Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.358 [2833.2833] INFO QobuzConnect: [0x7feaadd8]: Client initialized! Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.358 [2833.2833] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.364 [2833.2833] INFO LocalConfigManager: [0x7fea9ce8]: Starting Local Configuration server Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.364 [2833.2833] INFO SampleApp: Starting Local configuration server Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.364 [2833.2833] INFO SampleApp: Connected to UNIX socket client 0x7fe9fed8 Jun 19 07:11:05 volumio qobuz-connect[2833]: 20250619 07:11:05.504 [2833.2833] INFO SampleApp: Playback volume changed: 75 Jun 19 07:11:05 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:05 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:07 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 07:11:07 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 07:11:07 volumio volumio[1098]: info: Discovery: Getting this device information Jun 19 07:11:07 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:07 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:07 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 07:11:08 volumio volumio[1098]: info: TidalConnect service stoped! Jun 19 07:11:08 volumio volumio[1098]: info: TidalConnect service stoped! Jun 19 07:11:08 volumio volumio[1098]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 19 07:11:08 volumio volumio[1098]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 19 07:11:08 volumio sudo[2878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 19 07:11:08 volumio sudo[2878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:08 volumio systemd[1]: Started Volumio Tidal Connect Service. Jun 19 07:11:08 volumio sudo[2878]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:08 volumio volumio[1098]: info: Executing endpoint tc_getconfig Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 19 07:11:08 volumio vtcs[2884]: STARTING TidalConnect services, version: 1.5.1-beta1 Jun 19 07:11:08 volumio vtcs[2884]: STARTED TidalConnect services. Jun 19 07:11:08 volumio volumio[1098]: info: Executing endpoint tc_connect Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 19 07:11:08 volumio volumio[1098]: info: Connecting to TidalConnect Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jun 19 07:11:08 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:08 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:08 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:08 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:08 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:08 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:08 volumio volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jun 19 07:11:08 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:08 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:08 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:08 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:08 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:08 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:08 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:08 volumio volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 19 07:11:09 volumio volumio[1098]: info: VolumeController::SetAlsaVolumeNaN Jun 19 07:11:09 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:09 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:09 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 07:11:09 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:09 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:09 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:09 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:09 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:09 volumio volumio[1098]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 19 07:11:09 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:11 volumio volumio[1098]: info: TidalConnect service started! Jun 19 07:11:13 volumio volumio[1098]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 19 07:11:13 volumio volumio[1098]: info: Preparing to save Alsa Options, stopping services first Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioPause Jun 19 07:11:13 volumio volumio[1098]: info: CoreStateMachine::pause Jun 19 07:11:13 volumio volumio[1098]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 07:11:13 volumio volumio[1098]: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio volumio[1098]: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 07:11:13 volumio volumio[1098]: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio volumio[1098]: info: Setting mixer PCM for card Combo384 Amanero Jun 19 07:11:13 volumio volumio[1098]: info: QobuzConnect: setDeactiveState invoked Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:13 volumio vtcs[2884]: [2025-06-19 07:11:13.467] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 19 07:11:13 volumio vtcs[2884]: [2025-06-19 07:11:13.467] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 19 07:11:13 volumio sudo[2922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 19 07:11:13 volumio sudo[2922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:13 volumio volumio[1098]: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jun 19 07:11:13 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 19 07:11:13 volumio systemd[1]: vtcs.service: Succeeded. Jun 19 07:11:13 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jun 19 07:11:13 volumio sudo[2922]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 19 07:11:13 volumio volumio[1098]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 19 07:11:13 volumio volumio[1098]: info: Disabling external Volume Control Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:13 volumio volumio[1098]: info: Preparing to generate the ALSA configuration file Jun 19 07:11:13 volumio sudo[2940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 19 07:11:13 volumio sudo[2940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:13 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:13 volumio volumio[1098]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 19 07:11:13 volumio volumio[1098]: info: Reading ALSA contributions from plugins. Jun 19 07:11:13 volumio volumio[1098]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio sudo[2949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 19 07:11:13 volumio sudo[2940]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:13 volumio sudo[2949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:13 volumio sudo[2949]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:13 volumio volumio[1098]: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio sudo[2966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 19 07:11:13 volumio sudo[2966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 07:11:13 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 19 07:11:13 volumio qobuz-connect[2833]: 20250619 07:11:13.728 [2833.2833] INFO SampleApp: Stopping Local configuration server Jun 19 07:11:13 volumio qobuz-connect[2833]: 20250619 07:11:13.739 [2833.2833] INFO SampleApp: shat down connection on UNIX socket Jun 19 07:11:13 volumio systemd[1]: qobuz-connect.service: Succeeded. Jun 19 07:11:13 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 19 07:11:13 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jun 19 07:11:13 volumio sudo[2966]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:13 volumio volumio[1098]: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 07:11:13 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:13 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:11:13 volumio volumio[1098]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 19 07:11:13 volumio volumio[1098]: info: QobuzConnect: setDeactiveState invoked Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:13 volumio volumio[1098]: info: VolumeController:: Volume=undefined Mute =false Jun 19 07:11:13 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:13 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:13 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:13 volumio volumio[1098]: info: Executing endpoint qc_getconfig Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.852 [2978.2978] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.855 [2978.2978] INFO VolumeManager: [0x801a7408]: Setting new playback volume: 75 Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.855 [2978.2978] INFO VolumeManager: [0x801a7408]: Setting new mute state: 0 Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.856 [2978.2978] INFO QobuzConnect: [0x801a7dd8]: Client initialized! Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.856 [2978.2978] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jun 19 07:11:13 volumio volumio[1098]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 19 07:11:13 volumio volumio[1098]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 19 07:11:13 volumio volumio[1098]: info: Asound.conf file unchanged, so no further update is needed Jun 19 07:11:13 volumio volumio[1098]: info: Output device has changed, restarting MPD Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.863 [2978.2978] INFO LocalConfigManager: [0x801a6ce8]: Starting Local Configuration server Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.864 [2978.2978] INFO SampleApp: Starting Local configuration server Jun 19 07:11:13 volumio qobuz-connect[2978]: 20250619 07:11:13.864 [2978.2978] INFO SampleApp: Connected to UNIX socket client 0x8019ced8 Jun 19 07:11:13 volumio volumio[1098]: info: Output device has changed, restarting Shairport Sync Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 07:11:13 volumio sudo[2985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 07:11:13 volumio sudo[2985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:13 volumio sudo[2985]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:13 volumio sudo[2989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 07:11:13 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:13 volumio volumio[1098]: No protocol specified Jun 19 07:11:13 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:13 volumio volumio[1098]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:13 volumio systemd[1]: Stopping Music Player Daemon... Jun 19 07:11:13 volumio volumio[1098]: info: QobuzConnect: setDeactiveState invoked Jun 19 07:11:13 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:13 volumio sudo[3007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 19 07:11:14 volumio sudo[3010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 19 07:11:14 volumio qobuz-connect[2978]: 20250619 07:11:14.002 [2978.2978] INFO SampleApp: Playback volume changed: 75 Jun 19 07:11:14 volumio sudo[3007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:14 volumio sudo[3010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:14 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:14 volumio sudo[3023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 19 07:11:14 volumio volumio[1098]: info: MPD Permissions set Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:11:14 volumio sudo[3010]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio sudo[3007]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:14 volumio sudo[3023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:14 volumio systemd[1]: mpd.service: Succeeded. Jun 19 07:11:14 volumio systemd[1]: Stopped Music Player Daemon. Jun 19 07:11:14 volumio systemd[1]: Starting Music Player Daemon... Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 07:11:14 volumio sudo[3023]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:14 volumio volumio[1098]: info: Starting Shairport Sync Jun 19 07:11:14 volumio sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 19 07:11:14 volumio sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:14 volumio sudo[3051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 07:11:14 volumio sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:14 volumio qobuz-connect[2978]: 20250619 07:11:14.139 [2978.2978] INFO SampleApp: Stopping Local configuration server Jun 19 07:11:14 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:14 volumio qobuz-connect[2978]: 20250619 07:11:14.149 [2978.2978] INFO SampleApp: shat down connection on UNIX socket Jun 19 07:11:14 volumio volumio[1098]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 19 07:11:14 volumio volumio[1098]: info: QobuzConnect: setDeactiveState invoked Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:14 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:14 volumio systemd[1]: qobuz-connect.service: Succeeded. Jun 19 07:11:14 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:11:14 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jun 19 07:11:14 volumio sudo[3048]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 19 07:11:14 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 19 07:11:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 19 07:11:14 volumio sudo[3035]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:11:14 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:11:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 19 07:11:14 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:14 volumio sudo[3035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:14 volumio volumio[1098]: info: Executing endpoint qc_getconfig Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.207 [3062.3062] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 19 07:11:14 volumio volumio[1098]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 19 07:11:14 volumio volumio[1098]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.212 [3062.3062] INFO VolumeManager: [0x804f1408]: Setting new playback volume: 75 Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.212 [3062.3062] INFO VolumeManager: [0x804f1408]: Setting new mute state: 0 Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.212 [3062.3062] INFO QobuzConnect: [0x804f1dd8]: Client initialized! Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.212 [3062.3062] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jun 19 07:11:14 volumio sudo[3035]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.221 [3062.3062] INFO LocalConfigManager: [0x804f0ce8]: Starting Local Configuration server Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.221 [3062.3062] INFO SampleApp: Starting Local configuration server Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.221 [3062.3062] INFO SampleApp: Connected to UNIX socket client 0x804e6ed8 Jun 19 07:11:14 volumio volumio[1098]: info: Shairport-Sync Started Jun 19 07:11:14 volumio qobuz-connect[3062]: 20250619 07:11:14.357 [3062.3062] INFO SampleApp: Playback volume changed: 75 Jun 19 07:11:14 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:14 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:15 volumio mpd[3081]: Jun 19 07:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 07:11:15 volumio systemd[1]: Started Music Player Daemon. Jun 19 07:11:15 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:15 volumio volumio[1098]: error: updateQueue error: null Jun 19 07:11:16 volumio volumio[1098]: info: TidalConnect service stoped! Jun 19 07:11:16 volumio volumio[1098]: info: TidalConnect service stoped! Jun 19 07:11:16 volumio volumio[1098]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 19 07:11:16 volumio volumio[1098]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 19 07:11:16 volumio sudo[3113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 19 07:11:16 volumio sudo[3113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:16 volumio systemd[1]: Started Volumio Tidal Connect Service. Jun 19 07:11:16 volumio sudo[3113]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:16 volumio volumio[1098]: info: Executing endpoint tc_getconfig Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 19 07:11:16 volumio vtcs[3119]: STARTING TidalConnect services, version: 1.5.1-beta1 Jun 19 07:11:16 volumio vtcs[3119]: STARTED TidalConnect services. Jun 19 07:11:16 volumio volumio[1098]: info: Executing endpoint tc_connect Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 19 07:11:16 volumio volumio[1098]: info: Connecting to TidalConnect Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jun 19 07:11:16 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:16 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:16 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:16 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:16 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:16 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:16 volumio volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jun 19 07:11:16 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:16 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:16 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:16 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:16 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:16 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:16 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:16 volumio volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 19 07:11:17 volumio volumio[1098]: info: TidalConnect service stoped! Jun 19 07:11:17 volumio volumio[1098]: info: TidalConnect service stoped! Jun 19 07:11:17 volumio volumio[1098]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 19 07:11:17 volumio volumio[1098]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 19 07:11:17 volumio sudo[3150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 19 07:11:17 volumio sudo[3150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 07:11:17 volumio sudo[3150]: pam_unix(sudo:session): session closed for user root Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 19 07:11:17 volumio volumio[1098]: info: CoreStateMachine::getcurrentVolume Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 07:11:17 volumio volumio[1098]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 19 07:11:17 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:17 volumio volumio[1098]: info: VolumeController:: Volume=undefined Mute =false Jun 19 07:11:17 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:17 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:17 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:17 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:17 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:17 volumio volumio[1098]: info: CoreStateMachine::updateTrackBlock Jun 19 07:11:17 volumio volumio[1098]: info: CorePlayQueue::getTrackBlock Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 07:11:17 volumio volumio[1098]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 19 07:11:17 volumio volumio[1098]: xcb_connection_has_error() returned true Jun 19 07:11:17 volumio volumio[1098]: info: VolumeController:: Volume=undefined Mute =false Jun 19 07:11:17 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:17 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:17 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:17 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:17 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 07:11:17 volumio volumio[1098]: info: Discovery: Getting this device information Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:17 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:17 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 07:11:19 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:19 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:19 volumio volumio[1098]: info: TidalConnect service started! Jun 19 07:11:20 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 07:11:20 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 07:11:20 volumio volumio[1098]: info: Discovery: Getting this device information Jun 19 07:11:20 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:20 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:20 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 07:11:20 volumio volumio[1098]: info: TidalConnect service started! Jun 19 07:11:20 volumio volumio[1098]: info: CoreCommandRouter::volumioGetQueue Jun 19 07:11:20 volumio volumio[1098]: info: CoreStateMachine::getQueue Jun 19 07:11:20 volumio volumio[1098]: info: CorePlayQueue::getQueue Jun 19 07:11:21 volumio volumio[1098]: info: Executing endpoint metavolumio Jun 19 07:11:21 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 19 07:11:21 volumio volumio[1098]: info: Executing endpoint metavolumio Jun 19 07:11:21 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 19 07:11:21 volumio volumio[1098]: info: Executing endpoint metavolumio Jun 19 07:11:21 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 19 07:11:24 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 19 07:11:24 volumio volumio[1098]: info: handleBrowseUri took 191 milliseconds Jun 19 07:11:24 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:24 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:25 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 19 07:11:26 volumio volumio[1098]: info: handleBrowseUri took 1353 milliseconds Jun 19 07:11:26 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:31 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 19 07:11:31 volumio volumio[1098]: info: browseTIDALUri took 127 milliseconds Jun 19 07:11:31 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:31 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 19 07:11:36 volumio volumio[1098]: info: browseTIDALUri took 3142 milliseconds Jun 19 07:11:36 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:36 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:37 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 07:11:37 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 07:11:37 volumio volumio[1098]: info: Discovery: Getting this device information Jun 19 07:11:37 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:37 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:37 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 07:11:39 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 19 07:11:39 volumio volumio[1098]: info: CURURI: music-library Jun 19 07:11:39 volumio volumio[1098]: info: Preload queue cleared Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 19 07:11:42 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand update Jun 19 07:11:42 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jun 19 07:11:42 volumio volumio[1098]: info: Jun 19 07:11:42 volumio volumio[1098]: ---------------------------- MPD announces state update: update Jun 19 07:11:42 volumio volumio[1098]: info: sendMpdCommand update took 7 milliseconds Jun 19 07:11:42 volumio volumio[1098]: info: ControllerMpd::getState Jun 19 07:11:42 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jun 19 07:11:42 volumio volumio[1098]: info: sendMpdCommand status took 5 milliseconds Jun 19 07:11:42 volumio volumio[1098]: info: sendMpdCommand status took 2 milliseconds Jun 19 07:11:42 volumio volumio[1098]: verbose: ControllerMpd::parseState Jun 19 07:11:42 volumio volumio[1098]: info: Command Router : Notfying DB Updatetrue Jun 19 07:11:42 volumio volumio[1098]: verbose: ControllerMpd::parseState Jun 19 07:11:42 volumio volumio[1098]: info: ControllerMpd::pushState Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jun 19 07:11:42 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:42 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:42 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:42 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:42 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:42 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:42 volumio volumio[1098]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 19 07:11:42 volumio volumio[1098]: verbose: CURRENT POSITION 0 Jun 19 07:11:42 volumio volumio[1098]: info: CoreStateMachine::syncState stateService stop Jun 19 07:11:42 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Jun 19 07:11:42 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:42 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:42 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:42 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:42 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:42 volumio volumio[1098]: info: No code Jun 19 07:11:42 volumio volumio[1098]: info: CoreStateMachine::pushState Jun 19 07:11:42 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jun 19 07:11:42 volumio volumio[1098]: info: MRS: Pushing multiroomSync output update for this device Jun 19 07:11:42 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:11:42 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:42 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:42 volumio volumio[1098]: info: ------------------------------ 155ms Jun 19 07:11:44 volumio volumio[1098]: info: CoreCommandRouter::Close All Modals sent Jun 19 07:11:47 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 07:11:47 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 07:11:47 volumio volumio[1098]: info: Discovery: Getting this device information Jun 19 07:11:47 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:47 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:47 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 07:11:50 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 07:11:50 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 07:11:50 volumio volumio[1098]: info: Discovery: Getting this device information Jun 19 07:11:50 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:50 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:50 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 07:11:52 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:11:52 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:11:54 volumio volumio[1098]: info: Executing endpoint metavolumio Jun 19 07:11:54 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 19 07:11:54 volumio volumio[1098]: info: Executing endpoint metavolumio Jun 19 07:11:54 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 19 07:11:54 volumio volumio[1098]: info: Executing endpoint metavolumio Jun 19 07:11:54 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:58 volumio volumio[1098]: info: Retrieving Cloud Streaming UI Jun 19 07:11:58 volumio volumio[1098]: info: Getting Tidal Cloud Configuration Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:58 volumio volumio[1098]: info: Getting Qobuz Cloud Configuration Jun 19 07:11:58 volumio volumio[1098]: info: Asking plugin for UI Config Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:58 volumio volumio[1098]: info: Getting Spotify Cloud Configuration Jun 19 07:11:58 volumio volumio[1098]: info: Asking plugin for UI Config Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:58 volumio volumio[1098]: info: Saving Spotify Acccount Jun 19 07:11:58 volumio volumio[1098]: info: Got it Jun 19 07:11:58 volumio volumio[1098]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 19 07:11:58 volumio volumio[1098]: info: Got Tidal Cloud Configuration Jun 19 07:11:58 volumio volumio[1098]: info: Got it Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 07:11:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 19 07:12:02 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 07:12:04 volumio dhcpcd[1137]: eth0: carrier lost Jun 19 07:12:04 volumio dhcpcd[1292]: eth0: carrier lost Jun 19 07:12:04 volumio kernel: rk_gmac-dwmac ff290000.ethernet eth0: Link is Down Jun 19 07:12:04 volumio avahi-daemon[585]: Withdrawing address record for 192.168.0.121 on eth0. Jun 19 07:12:04 volumio avahi-daemon[585]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.121. Jun 19 07:12:04 volumio avahi-daemon[585]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 19 07:12:04 volumio volumio[1098]: info: Discovery: A device disappeared from network Jun 19 07:12:04 volumio volumio[1098]: info: Discovery: Device rivo disappeared from network Jun 19 07:12:04 volumio volumio[1098]: info: Discovery: Disconnected from remote: 192.168.0.122 Jun 19 07:12:04 volumio volumio[1098]: info: MRS: Cleaning up device: c315c77b-b22f-41d6-aaea-23d76d53336b Jun 19 07:12:04 volumio volumio[1098]: info: Removing audio output: Jun 19 07:12:04 volumio dhcpcd[1292]: eth0: deleting route to 192.168.0.0/24 Jun 19 07:12:05 volumio volumio[1098]: info: Discovery: A device disappeared from network Jun 19 07:12:05 volumio volumio[1098]: info: Discovery: Device volumio disappeared from network Jun 19 07:12:04 volumio dhcpcd[1292]: eth0: deleting default route via 192.168.0.1 Jun 19 07:12:04 volumio dhcpcd[1137]: eth0: deleting route to 192.168.0.0/24 Jun 19 07:12:04 volumio dhcpcd[1137]: eth0: deleting default route via 192.168.0.1 Jun 19 07:12:05 volumio ifplugd(eth0)[833]: Link beat lost. Jun 19 07:12:06 volumio volumio[1098]: info: Volumio Network Manager: Network status updated: 2 Jun 19 07:12:06 volumio ntpd[1327]: Deleting interface #3 eth0, 192.168.0.121#123, interface stats: received=141, sent=142, dropped=0, active_time=142 secs Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.221 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.215 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.219 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.222 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.220 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.213 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.217 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.212 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.216 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.218 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 125.229.162.223 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.214 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.147.22.149 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio ntpd[1327]: 103.186.118.211 local addr 192.168.0.121 -> Jun 19 07:12:06 volumio dhcpcd[1292]: eth0: carrier acquired Jun 19 07:12:06 volumio dhcpcd[1137]: eth0: carrier acquired Jun 19 07:12:06 volumio dhcpcd[1137]: eth0: IAID 26:c4:fb:96 Jun 19 07:12:06 volumio dhcpcd[1292]: eth0: IAID 26:c4:fb:96 Jun 19 07:12:06 volumio kernel: setLedConfiguration: #### before setting led, Reg26 = 0x91 , Reg28 = 0x9040 Jun 19 07:12:06 volumio kernel: setLedConfiguration: #### after setting led, Reg26 = 0x91 , Reg28 = 0x9040 Jun 19 07:12:06 volumio kernel: rk_gmac-dwmac ff290000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jun 19 07:12:07 volumio dhcpcd[1292]: eth0: soliciting an IPv6 router Jun 19 07:12:07 volumio ifplugd(eth0)[833]: Link beat detected. Jun 19 07:12:07 volumio dhcpcd[1137]: eth0: rebinding lease of 192.168.0.121 Jun 19 07:12:07 volumio dhcpcd[1137]: eth0: probing address 192.168.0.121/24 Jun 19 07:12:07 volumio dhcpcd[1137]: eth0: soliciting an IPv6 router Jun 19 07:12:07 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 07:12:07 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 07:12:07 volumio volumio[1098]: info: Discovery: Getting this device information Jun 19 07:12:07 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:12:07 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:12:07 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 07:12:07 volumio dhcpcd[1292]: eth0: rebinding lease of 192.168.0.121 Jun 19 07:12:07 volumio dhcpcd[1292]: eth0: probing address 192.168.0.121/24 Jun 19 07:12:07 volumio ntpd[1327]: Soliciting pool server 2600:1900:4030:401f:: Jun 19 07:12:11 volumio dhcpcd[1137]: eth0: leased 192.168.0.121 for 7200 seconds Jun 19 07:12:11 volumio dhcpcd[1137]: eth0: adding route to 192.168.0.0/24 Jun 19 07:12:11 volumio avahi-daemon[585]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.121. Jun 19 07:12:11 volumio dhcpcd[1137]: eth0: adding default route via 192.168.0.1 Jun 19 07:12:11 volumio avahi-daemon[585]: New relevant interface eth0.IPv4 for mDNS. Jun 19 07:12:11 volumio avahi-daemon[585]: Registering new address record for 192.168.0.121 on eth0.IPv4. Jun 19 07:12:11 volumio systemd[1]: Stopping Network Time Service... Jun 19 07:12:11 volumio ntpd[1327]: ntpd exiting on signal 15 (Terminated) Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.211 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:12:11 volumio ntpd[1327]: 103.147.22.149 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.214 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 125.229.162.223 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.218 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.216 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.212 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.217 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.213 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.220 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.222 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.219 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.215 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio ntpd[1327]: 103.186.118.221 local addr 192.168.0.44 -> Jun 19 07:12:11 volumio systemd[1]: ntp.service: Succeeded. Jun 19 07:12:11 volumio systemd[1]: Stopped Network Time Service. Jun 19 07:12:11 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:12:11 volumio systemd[1]: Starting Network Time Service... Jun 19 07:12:11 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:12:12 volumio ntpd[3451]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 19 07:12:12 volumio ntpd[3451]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 19 07:12:12 volumio systemd[1]: Started Network Time Service. Jun 19 07:12:12 volumio ntpd[3457]: proto: precision = 0.583 usec (-21) Jun 19 07:12:12 volumio ntpd[3457]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 19 07:12:12 volumio ntpd[3457]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 19 07:12:12 volumio ntpd[3457]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 904 days ago Jun 19 07:12:12 volumio ntpd[3457]: Listen and drop on 0 v6wildcard [::]:123 Jun 19 07:12:12 volumio ntpd[3457]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 19 07:12:12 volumio ntpd[3457]: Listen normally on 2 lo 127.0.0.1:123 Jun 19 07:12:12 volumio ntpd[3457]: Listen normally on 3 eth0 192.168.0.121:123 Jun 19 07:12:12 volumio ntpd[3457]: Listen normally on 4 wlan0 192.168.0.44:123 Jun 19 07:12:12 volumio ntpd[3457]: Listening on routing socket on fd #21 for interface updates Jun 19 07:12:12 volumio ntpd[3457]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 19 07:12:12 volumio ntpd[3457]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 19 07:12:12 volumio volumio[1098]: info: Discovery: adding c315c77b-b22f-41d6-aaea-23d76d53336b Jun 19 07:12:12 volumio volumio[1098]: info: Discovery: Found device Rivo Jun 19 07:12:12 volumio volumio[1098]: info: Discovery: Connecting to remote: 192.168.0.122 Jun 19 07:12:12 volumio volumio[1098]: info: MRS: Pushing audio output on request Jun 19 07:12:12 volumio volumio[1098]: info: MRS: Pushing multiroomSync output Jun 19 07:12:12 volumio volumio[1098]: info: Discovery: Connected to remote: 192.168.0.122 Jun 19 07:12:12 volumio dhcpcd[1292]: eth0: leased 192.168.0.121 for 7200 seconds Jun 19 07:12:12 volumio dhcpcd[1292]: eth0: adding route to 192.168.0.0/24 Jun 19 07:12:12 volumio dhcpcd[1292]: eth0: adding default route via 192.168.0.1 Jun 19 07:12:12 volumio ntpd[3457]: ntpd exiting on signal 15 (Terminated) Jun 19 07:12:12 volumio systemd[1]: Stopping Network Time Service... Jun 19 07:12:12 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:12:12 volumio systemd[1]: ntp.service: Succeeded. Jun 19 07:12:12 volumio systemd[1]: Stopped Network Time Service. Jun 19 07:12:12 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 19 07:12:12 volumio systemd[1]: Starting Network Time Service... Jun 19 07:12:12 volumio volumio[1098]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 19 07:12:12 volumio ntpd[3492]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 19 07:12:12 volumio ntpd[3492]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 19 07:12:12 volumio systemd[1]: Started Network Time Service. Jun 19 07:12:12 volumio ntpd[3498]: proto: precision = 0.583 usec (-21) Jun 19 07:12:12 volumio ntpd[3498]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 19 07:12:12 volumio ntpd[3498]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 19 07:12:12 volumio ntpd[3498]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 904 days ago Jun 19 07:12:12 volumio ntpd[3498]: Listen and drop on 0 v6wildcard [::]:123 Jun 19 07:12:12 volumio ntpd[3498]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 19 07:12:12 volumio ntpd[3498]: Listen normally on 2 lo 127.0.0.1:123 Jun 19 07:12:12 volumio ntpd[3498]: Listen normally on 3 eth0 192.168.0.121:123 Jun 19 07:12:12 volumio ntpd[3498]: Listen normally on 4 wlan0 192.168.0.44:123 Jun 19 07:12:12 volumio ntpd[3498]: Listening on routing socket on fd #21 for interface updates Jun 19 07:12:12 volumio ntpd[3498]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 19 07:12:12 volumio ntpd[3498]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 19 07:12:12 volumio volumio[1098]: info: Discovery: adding e987faae-119b-4d15-a8cf-7dc5ed075955 Jun 19 07:12:12 volumio volumio[1098]: info: Discovery: Found device Volumio Jun 19 07:12:12 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jun 19 07:12:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jun 19 07:12:12 volumio volumio[1098]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 07:12:12 volumio volumio[1098]: Error: The value NaN is not a number Jun 19 07:12:12 volumio volumio[1098]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 19 07:12:12 volumio volumio[1098]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 19 07:12:12 volumio volumio[1098]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 19 07:12:12 volumio volumio[1098]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 19 07:12:12 volumio volumio[1098]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 19 07:12:12 volumio volumio[1098]: at Browser.emit (events.js:400:28) Jun 19 07:12:12 volumio volumio[1098]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 19 07:12:12 volumio volumio[1098]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 19 07:12:12 volumio volumio[1098]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 19 07:12:12 volumio volumio[1098]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 19 07:12:12 volumio volumio[1098]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 19 07:12:12 volumio volumio[1098]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 19 07:12:12 volumio volumio[1098]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 19 07:12:12 volumio volumio[1098]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 07:12:13 volumio sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 07:11 Jun 19 07:12:13 volumio sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:12:56 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="f5ff903d28fee878b80ffec17b533674"