-- Logs begin at Fri 2025-12-05 18:10:53 GMT, end at Fri 2025-12-05 18:16:21 GMT. -- Dec 05 18:15:01 primo volumio[1070]: info: VolumeController::SetAlsaVolume79 Dec 05 18:15:01 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:01 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:01 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:01 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:01 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:01 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:01 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:01 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:01 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:01 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:02 primo volumio[1070]: info: VolumeController::SetAlsaVolume100 Dec 05 18:15:02 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:02 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:02 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:02 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:02 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:02 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:02 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:02 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:02 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:02 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 18:15:10 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:10 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 18:15:10 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:10 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 18:15:15 primo volumio[1070]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 05 18:15:15 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 05 18:15:15 primo volumio[1070]: info: Preparing to save Alsa Options, stopping services first Dec 05 18:15:15 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:15 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:15 primo volumio[1070]: info: CoreCommandRouter::volumioPause Dec 05 18:15:15 primo volumio[1070]: info: CoreStateMachine::pause Dec 05 18:15:15 primo volumio[1070]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Analog RCA Output"}} Dec 05 18:15:15 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:15 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo volumio[1070]: info: Setting mixer Digital for card Analog RCA Output Dec 05 18:15:16 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo vtcs[3263]: [2025-12-05 18:15:16.086] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 05 18:15:16 primo vtcs[3263]: [2025-12-05 18:15:16.086] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 05 18:15:16 primo sudo[3902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:16 primo sudo[3902]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo systemd[1]: Stopping Volumio Tidal Connect Service... Dec 05 18:15:16 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:16 primo systemd[1]: vtcs.service: Succeeded. Dec 05 18:15:16 primo systemd[1]: Stopped Volumio Tidal Connect Service. Dec 05 18:15:16 primo sudo[3902]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 05 18:15:16 primo volumio[1070]: info: Updating Volume Controller Parameters: Device: 0 Name: Analog RCA Output Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 05 18:15:16 primo volumio[1070]: info: Disabling external Volume Control Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 18:15:16 primo volumio[1070]: info: Preparing to generate the ALSA configuration file Dec 05 18:15:16 primo sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:16 primo sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:16 primo volumio[1070]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 05 18:15:16 primo volumio[1070]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 05 18:15:16 primo volumio[1070]: info: Reading ALSA contributions from plugins. Dec 05 18:15:16 primo sudo[3921]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 18:15:16 primo sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo sudo[3931]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo sudo[3947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 18:15:16 primo sudo[3947]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:16 primo qobuz-connect[3225]: 20251205 18:15:16.376 [3225.3225] INFO SampleApp: Stopping Local configuration server Dec 05 18:15:16 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 05 18:15:16 primo qobuz-connect[3225]: 20251205 18:15:16.386 [3225.3225] INFO SampleApp: shat down connection on UNIX socket Dec 05 18:15:16 primo systemd[1]: qobuz-connect.service: Succeeded. Dec 05 18:15:16 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 05 18:15:16 primo systemd[1]: Started Volumio Qobuz Connect Service. Dec 05 18:15:16 primo sudo[3947]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:16 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:16 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:16 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:16 primo volumio[1070]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 05 18:15:16 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:16 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:16 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo volumio[1070]: info: Executing endpoint qc_getconfig Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.517 [3959.3959] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 18:15:16 primo volumio[1070]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 18:15:16 primo volumio[1070]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 18:15:16 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.179:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.523 [3959.3959] INFO VolumeManager: [0x800eeb70]: Setting new playback volume: 75 Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.523 [3959.3959] INFO VolumeManager: [0x800eeb70]: Setting new mute state: 0 Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.523 [3959.3959] INFO QobuzConnect: [0x800ef540]: Client initialized! Dec 05 18:15:16 primo volumio[1070]: info: Asound.conf file unchanged, so no further update is needed Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.524 [3959.3959] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Dec 05 18:15:16 primo volumio[1070]: info: Output device has changed, restarting MPD Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.530 [3959.3959] INFO LocalConfigManager: [0x800ee450]: Starting Local Configuration server Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.531 [3959.3959] INFO SampleApp: Starting Local configuration server Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.531 [3959.3959] INFO SampleApp: Connected to UNIX socket client 0x800e3ed8 Dec 05 18:15:16 primo volumio[1070]: info: Output device has changed, restarting Shairport Sync Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:16 primo sudo[3970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 18:15:16 primo sudo[3970]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo sudo[3973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 18:15:16 primo sudo[3970]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo volumio[1070]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:16 primo systemd[1]: Stopping Music Player Daemon... Dec 05 18:15:16 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo sudo[3992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:16 primo systemd[1]: mpd.service: Succeeded. Dec 05 18:15:16 primo systemd[1]: Stopped Music Player Daemon. Dec 05 18:15:16 primo systemd[1]: Starting Music Player Daemon... Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.667 [3959.3959] INFO SampleApp: Playback volume changed: 75 Dec 05 18:15:16 primo sudo[3992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo sudo[3996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:16 primo sudo[3996]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo sudo[3992]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:16 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:16 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:16 primo volumio[1070]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 05 18:15:16 primo volumio[1070]: info: Preparing to save Alsa Options, stopping services first Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioPause Dec 05 18:15:16 primo volumio[1070]: info: CoreStateMachine::pause Dec 05 18:15:16 primo volumio[1070]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Analog RCA Output"}} Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:16 primo sudo[3996]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo sudo[4012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 18:15:16 primo sudo[3997]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 18:15:16 primo sudo[4012]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo sudo[3997]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo sudo[4012]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo sudo[3997]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo sudo[4041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 18:15:16 primo sudo[4041]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.817 [3959.3959] INFO SampleApp: Stopping Local configuration server Dec 05 18:15:16 primo qobuz-connect[3959]: 20251205 18:15:16.827 [3959.3959] INFO SampleApp: shat down connection on UNIX socket Dec 05 18:15:16 primo systemd[1]: qobuz-connect.service: Succeeded. Dec 05 18:15:16 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 05 18:15:16 primo systemd[1]: Started Volumio Qobuz Connect Service. Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:16 primo sudo[4041]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo volumio[1070]: info: Setting mixer Digital for card Analog RCA Output Dec 05 18:15:16 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:16 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:16 primo sudo[4062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:16 primo sudo[4062]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:16 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:16 primo sudo[4062]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 05 18:15:16 primo volumio[1070]: info: Updating Volume Controller Parameters: Device: 0 Name: Analog RCA Output Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 05 18:15:16 primo volumio[1070]: info: Disabling external Volume Control Dec 05 18:15:16 primo volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 18:15:16 primo volumio[1070]: info: Preparing to generate the ALSA configuration file Dec 05 18:15:17 primo sudo[4080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:17 primo sudo[4080]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Dec 05 18:15:17 primo volumio[1070]: info: MPD Permissions set Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 05 18:15:17 primo volumio[1070]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 05 18:15:17 primo volumio[1070]: info: Reading ALSA contributions from plugins. Dec 05 18:15:17 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:17 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:17 primo sudo[4080]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo sudo[4089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo sudo[4089]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo sudo[4089]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo volumio[1070]: info: Executing endpoint qc_getconfig Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.144 [4053.4053] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.148 [4053.4053] INFO VolumeManager: [0x8026cb70]: Setting new playback volume: 75 Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.148 [4053.4053] INFO VolumeManager: [0x8026cb70]: Setting new mute state: 0 Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.148 [4053.4053] INFO QobuzConnect: [0x8026d540]: Client initialized! Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.148 [4053.4053] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:17 primo sudo[4105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.160 [4053.4053] INFO LocalConfigManager: [0x8026c450]: Starting Local Configuration server Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.160 [4053.4053] INFO SampleApp: Starting Local configuration server Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.161 [4053.4053] INFO SampleApp: Connected to UNIX socket client 0x80261ed8 Dec 05 18:15:17 primo sudo[4105]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:17 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:17 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.203 [4053.4053] INFO SampleApp: Stopping Local configuration server Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 18:15:17 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:17 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:17 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo qobuz-connect[4053]: 20251205 18:15:17.213 [4053.4053] INFO SampleApp: shat down connection on UNIX socket Dec 05 18:15:17 primo systemd[1]: qobuz-connect.service: Succeeded. Dec 05 18:15:17 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 05 18:15:17 primo systemd[1]: Started Volumio Qobuz Connect Service. Dec 05 18:15:17 primo sudo[4105]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 18:15:17 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:17 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:17 primo volumio[1070]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 05 18:15:17 primo volumio[1070]: info: Preparing to save Alsa Options, stopping services first Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioPause Dec 05 18:15:17 primo volumio[1070]: info: CoreStateMachine::pause Dec 05 18:15:17 primo volumio[1070]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Analog RCA Output"}} Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: Setting mixer Digital for card Analog RCA Output Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo sudo[4149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:17 primo sudo[4149]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo sudo[4149]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 05 18:15:17 primo volumio[1070]: info: Updating Volume Controller Parameters: Device: 0 Name: Analog RCA Output Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 05 18:15:17 primo volumio[1070]: info: Disabling external Volume Control Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 18:15:17 primo sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:17 primo sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo sudo[4175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 18:15:17 primo sudo[4167]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.248:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:17 primo volumio[1070]: info: Executing endpoint qc_getconfig Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 18:15:17 primo sudo[4175]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.736 [4119.4119] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.740 [4119.4119] INFO VolumeManager: [0x80803b70]: Setting new playback volume: 75 Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.740 [4119.4119] INFO VolumeManager: [0x80803b70]: Setting new mute state: 0 Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.740 [4119.4119] INFO QobuzConnect: [0x80804540]: Client initialized! Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.740 [4119.4119] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Dec 05 18:15:17 primo sudo[4175]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.749 [4119.4119] INFO LocalConfigManager: [0x80803450]: Starting Local Configuration server Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.750 [4119.4119] INFO SampleApp: Starting Local configuration server Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.750 [4119.4119] INFO SampleApp: Connected to UNIX socket client 0x807f8ed8 Dec 05 18:15:17 primo volumio[1070]: info: Starting Shairport Sync Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:17 primo sudo[4194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 18:15:17 primo volumio[1070]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 18:15:17 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:17 primo sudo[4194]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo sudo[4198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 05 18:15:17 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:17 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:17 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:17 primo sudo[4198]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:17 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.818 [4119.4119] INFO SampleApp: Stopping Local configuration server Dec 05 18:15:17 primo qobuz-connect[4119]: 20251205 18:15:17.828 [4119.4119] INFO SampleApp: shat down connection on UNIX socket Dec 05 18:15:17 primo systemd[1]: qobuz-connect.service: Succeeded. Dec 05 18:15:17 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 05 18:15:17 primo systemd[1]: Started Volumio Qobuz Connect Service. Dec 05 18:15:17 primo sudo[4194]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 05 18:15:17 primo systemd[1]: shairport-sync.service: Succeeded. Dec 05 18:15:17 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 05 18:15:17 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 05 18:15:17 primo sudo[4198]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 18:15:17 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:17 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo volumio[1070]: info: Asound.conf file unchanged, so no further update is needed Dec 05 18:15:18 primo volumio[1070]: info: Output device has changed, restarting MPD Dec 05 18:15:18 primo mpd[4055]: Dec 05 18:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 18:15:18 primo sudo[4244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 18:15:18 primo volumio[1070]: info: Output device has changed, restarting Shairport Sync Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:18 primo sudo[4244]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo sudo[4244]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo sudo[4248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 18:15:18 primo sudo[4248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo systemd[1]: Started Music Player Daemon. Dec 05 18:15:18 primo sudo[3973]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:18 primo volumio[1070]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo systemd[1]: Stopping Music Player Daemon... Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:18 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:18 primo volumio[1070]: info: Preparing to generate the ALSA configuration file Dec 05 18:15:18 primo sudo[4272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:18 primo sudo[4272]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo systemd[1]: mpd.service: Succeeded. Dec 05 18:15:18 primo systemd[1]: Stopped Music Player Daemon. Dec 05 18:15:18 primo systemd[1]: Starting Music Player Daemon... Dec 05 18:15:18 primo sudo[4272]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo sudo[4280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:18 primo sudo[4280]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo volumio[1070]: info: Shairport-Sync Started Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:18 primo sudo[4280]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:18 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:18 primo sudo[4294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 18:15:18 primo sudo[4278]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 18:15:18 primo volumio[1070]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 05 18:15:18 primo volumio[1070]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 05 18:15:18 primo volumio[1070]: info: Reading ALSA contributions from plugins. Dec 05 18:15:18 primo sudo[4294]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo volumio[1070]: error: [ytcr] Error connecting MPD: Dec 05 18:15:18 primo volumio[1070]: (Error) write EPIPE Dec 05 18:15:18 primo volumio[1070]: Error: write EPIPE Dec 05 18:15:18 primo volumio[1070]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Dec 05 18:15:18 primo volumio[1070]: at writeGeneric (internal/stream_base_commons.js:147:3) Dec 05 18:15:18 primo volumio[1070]: at Socket._writeGeneric (net.js:798:11) Dec 05 18:15:18 primo volumio[1070]: at Socket._write (net.js:810:8) Dec 05 18:15:18 primo volumio[1070]: at writeOrBuffer (internal/streams/writable.js:358:12) Dec 05 18:15:18 primo volumio[1070]: at Socket.Writable.write (internal/streams/writable.js:303:10) Dec 05 18:15:18 primo volumio[1070]: at Socket.onData (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:271:16) Dec 05 18:15:18 primo volumio[1070]: at Socket.emit (events.js:400:28) Dec 05 18:15:18 primo volumio[1070]: at addChunk (internal/streams/readable.js:293:12) Dec 05 18:15:18 primo volumio[1070]: at readableAddChunk (internal/streams/readable.js:263:11) Dec 05 18:15:18 primo volumio[1070]: at Socket.Readable.push (internal/streams/readable.js:206:10) Dec 05 18:15:18 primo volumio[1070]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 05 18:15:18 primo volumio[1070]: Retrying in 5 seconds... Dec 05 18:15:18 primo sudo[4278]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo sudo[4294]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo sudo[4278]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: info: Executing endpoint qc_getconfig Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 18:15:18 primo sudo[4315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 18:15:18 primo volumio[1070]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.337 [4212.4212] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.341 [4212.4212] INFO VolumeManager: [0x809bfb70]: Setting new playback volume: 75 Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.341 [4212.4212] INFO VolumeManager: [0x809bfb70]: Setting new mute state: 0 Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.341 [4212.4212] INFO QobuzConnect: [0x809c0540]: Client initialized! Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.341 [4212.4212] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Dec 05 18:15:18 primo sudo[4315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo volumio[1070]: info: MPD Permissions set Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.354 [4212.4212] INFO LocalConfigManager: [0x809bf450]: Starting Local Configuration server Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.354 [4212.4212] INFO SampleApp: Starting Local configuration server Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.354 [4212.4212] INFO SampleApp: Connected to UNIX socket client 0x809b4ed8 Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.361 [4212.4212] INFO SampleApp: Stopping Local configuration server Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo qobuz-connect[4212]: 20251205 18:15:18.371 [4212.4212] INFO SampleApp: shat down connection on UNIX socket Dec 05 18:15:18 primo systemd[1]: qobuz-connect.service: Succeeded. Dec 05 18:15:18 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 05 18:15:18 primo systemd[1]: Started Volumio Qobuz Connect Service. Dec 05 18:15:18 primo sudo[4315]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:18 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:18 primo volumio[1070]: info: Executing endpoint qc_getconfig Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.449 [4326.4326] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 18:15:18 primo volumio[1070]: info: Starting Shairport Sync Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.453 [4326.4326] INFO VolumeManager: [0x7fdf9b70]: Setting new playback volume: 75 Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.453 [4326.4326] INFO VolumeManager: [0x7fdf9b70]: Setting new mute state: 0 Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.453 [4326.4326] INFO QobuzConnect: [0x7fdfa540]: Client initialized! Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.453 [4326.4326] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.460 [4326.4326] INFO LocalConfigManager: [0x7fdf9450]: Starting Local Configuration server Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.460 [4326.4326] INFO SampleApp: Starting Local configuration server Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.461 [4326.4326] INFO SampleApp: Connected to UNIX socket client 0x7fdeeed8 Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 18:15:18 primo volumio[1070]: info: Asound.conf file unchanged, so no further update is needed Dec 05 18:15:18 primo volumio[1070]: info: Output device has changed, restarting MPD Dec 05 18:15:18 primo sudo[4340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 05 18:15:18 primo sudo[4340]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo sudo[4345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 18:15:18 primo volumio[1070]: info: Output device has changed, restarting Shairport Sync Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:18 primo sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo sudo[4349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 18:15:18 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 05 18:15:18 primo systemd[1]: shairport-sync.service: Succeeded. Dec 05 18:15:18 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 05 18:15:18 primo sudo[4345]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 05 18:15:18 primo sudo[4340]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo sudo[4349]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:18 primo volumio[1070]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.599 [4326.4326] INFO SampleApp: Playback volume changed: 75 Dec 05 18:15:18 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:18 primo systemd[1]: mpd.service: Succeeded. Dec 05 18:15:18 primo systemd[1]: Stopped Music Player Daemon. Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:18 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:18 primo systemd[1]: Starting Music Player Daemon... Dec 05 18:15:18 primo sudo[4378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:18 primo sudo[4378]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo sudo[4376]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 18:15:18 primo sudo[4383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 18:15:18 primo sudo[4378]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo sudo[4376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo sudo[4383]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo sudo[4376]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:18 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:18 primo volumio[1070]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Dec 05 18:15:18 primo volumio[1070]: info: MPD Permissions set Dec 05 18:15:18 primo volumio[1070]: info: Shairport-Sync Started Dec 05 18:15:18 primo sudo[4383]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo sudo[4402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 18:15:18 primo sudo[4402]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo sudo[4402]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:18 primo volumio[1070]: info: Starting Shairport Sync Dec 05 18:15:18 primo sudo[4426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 18:15:18 primo sudo[4426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo sudo[4432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.822 [4326.4326] INFO SampleApp: Stopping Local configuration server Dec 05 18:15:18 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo sudo[4432]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:18 primo qobuz-connect[4326]: 20251205 18:15:18.833 [4326.4326] INFO SampleApp: shat down connection on UNIX socket Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: setDeactiveState invoked Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:18 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:18 primo systemd[1]: qobuz-connect.service: Succeeded. Dec 05 18:15:18 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo systemd[1]: Started Volumio Qobuz Connect Service. Dec 05 18:15:18 primo sudo[4426]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 05 18:15:18 primo systemd[1]: shairport-sync.service: Succeeded. Dec 05 18:15:18 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:18 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:18 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 05 18:15:18 primo sudo[4432]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:18 primo volumio[1070]: info: Executing endpoint qc_getconfig Dec 05 18:15:18 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.915 [4439.4439] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.918 [4439.4439] INFO VolumeManager: [0x8071fb70]: Setting new playback volume: 75 Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.919 [4439.4439] INFO VolumeManager: [0x8071fb70]: Setting new mute state: 0 Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.919 [4439.4439] INFO QobuzConnect: [0x80720540]: Client initialized! Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.919 [4439.4439] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Dec 05 18:15:18 primo volumio[1070]: info: Shairport-Sync Started Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 18:15:18 primo volumio[1070]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.927 [4439.4439] INFO LocalConfigManager: [0x8071f450]: Starting Local Configuration server Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.927 [4439.4439] INFO SampleApp: Starting Local configuration server Dec 05 18:15:18 primo qobuz-connect[4439]: 20251205 18:15:18.927 [4439.4439] INFO SampleApp: Connected to UNIX socket client 0x80714ed8 Dec 05 18:15:19 primo qobuz-connect[4439]: 20251205 18:15:19.065 [4439.4439] INFO SampleApp: Playback volume changed: 75 Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:19 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:19 primo volumio[1070]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:19 primo volumio[1070]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:19 primo sudo[4466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 18:15:19 primo sudo[4466]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:19 primo systemd[1]: Started Volumio Tidal Connect Service. Dec 05 18:15:19 primo sudo[4466]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:19 primo volumio[1070]: info: Executing endpoint tc_getconfig Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 05 18:15:19 primo vtcs[4472]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 05 18:15:19 primo vtcs[4472]: STARTED TidalConnect services. Dec 05 18:15:19 primo volumio[1070]: info: Executing endpoint tc_connect Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 05 18:15:19 primo volumio[1070]: info: Connecting to TidalConnect Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::servicePushState Dec 05 18:15:19 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:19 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:19 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::servicePushState Dec 05 18:15:19 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:19 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:19 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 05 18:15:19 primo mpd[4423]: Dec 05 18:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 18:15:19 primo systemd[1]: Started Music Player Daemon. Dec 05 18:15:19 primo volumio[1070]: error: updateQueue error: null Dec 05 18:15:19 primo sudo[4248]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:19 primo sudo[4349]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:19 primo volumio[1070]: error: MPD error: The expression evaluated to a falsy value: Dec 05 18:15:19 primo volumio[1070]: assert.ok(self.idling) Dec 05 18:15:19 primo volumio[1070]: error: The expression evaluated to a falsy value: Dec 05 18:15:19 primo volumio[1070]: assert.ok(self.idling) Dec 05 18:15:19 primo volumio[1070]: error: updateQueue error: null Dec 05 18:15:19 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:19 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:19 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:19 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.179:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:19 primo volumio[1070]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:19 primo volumio[1070]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:19 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:19 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:19 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.248:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:19 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:19 primo sudo[4511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 18:15:19 primo sudo[4511]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:19 primo sudo[4511]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:19 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:20 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:20 primo volumio[1070]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:20 primo volumio[1070]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:20 primo sudo[4530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 18:15:20 primo sudo[4530]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:20 primo sudo[4530]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 05 18:15:20 primo volumio[1070]: info: CoreStateMachine::getcurrentVolume Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 18:15:20 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:20 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:20 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreStateMachine::updateTrackBlock Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrackBlock Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 18:15:20 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:20 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:20 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:20 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:20 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.179:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:20 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:20 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:20 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:20 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.248:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:20 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:20 primo volumio[1070]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:20 primo volumio[1070]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:20 primo sudo[4554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 18:15:20 primo sudo[4554]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:20 primo sudo[4554]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 05 18:15:20 primo volumio[1070]: info: CoreStateMachine::getcurrentVolume Dec 05 18:15:20 primo volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 18:15:21 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreStateMachine::updateTrackBlock Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrackBlock Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 18:15:21 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:21 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:21 primo volumio[1070]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:21 primo volumio[1070]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:21 primo sudo[4577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 18:15:21 primo sudo[4577]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:21 primo sudo[4581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 18:15:21 primo sudo[4577]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:21 primo sudo[4581]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:21 primo systemd[1]: Stopping MPD Monitor Service... Dec 05 18:15:21 primo systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:21 primo systemd[1]: mpd_monitor.service: Succeeded. Dec 05 18:15:21 primo systemd[1]: Stopped MPD Monitor Service. Dec 05 18:15:21 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:21 primo systemd[1]: Started MPD Monitor Service. Dec 05 18:15:21 primo sudo[4581]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:21 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:21 primo mpd_monitor.sh[4594]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 18:15:21 primo volumio[1070]: info: Successfully started MPD Monitor Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:21 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 05 18:15:21 primo volumio[1070]: info: CoreStateMachine::getcurrentVolume Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 18:15:21 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.179:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:21 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:21 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreStateMachine::updateTrackBlock Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrackBlock Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Dec 05 18:15:21 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.248:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:21 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:21 primo volumio[1070]: info: VolumeController:: Volume=100 Mute =false Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreStateMachine::pushState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioPushState Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Dec 05 18:15:21 primo volumio[1070]: info: MRS: Pushing multiroomSync output Dec 05 18:15:21 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:21 primo volumio[1070]: info: TidalConnect service stoped! Dec 05 18:15:21 primo volumio[1070]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:21 primo volumio[1070]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 18:15:21 primo sudo[4614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 18:15:21 primo sudo[4614]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:21 primo sudo[4614]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:22 primo volumio[1070]: info: TidalConnect service started! Dec 05 18:15:22 primo sudo[4625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 18:15:22 primo sudo[4625]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:22 primo sudo[4628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 18:15:22 primo sudo[4628]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:22 primo systemd[1]: Stopping MPD Monitor Service... Dec 05 18:15:22 primo systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:22 primo systemd[1]: mpd_monitor.service: Succeeded. Dec 05 18:15:22 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:22 primo systemd[1]: Stopped MPD Monitor Service. Dec 05 18:15:22 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:22 primo systemd[1]: Started MPD Monitor Service. Dec 05 18:15:22 primo sudo[4625]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:22 primo mpd_monitor.sh[4638]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 18:15:22 primo systemd[1]: Stopping MPD Monitor Service... Dec 05 18:15:22 primo systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:22 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:22 primo systemd[1]: mpd_monitor.service: Succeeded. Dec 05 18:15:22 primo systemd[1]: Stopped MPD Monitor Service. Dec 05 18:15:22 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:22 primo systemd[1]: Started MPD Monitor Service. Dec 05 18:15:22 primo sudo[4628]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:22 primo mpd_monitor.sh[4645]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 18:15:22 primo volumio[1070]: info: Successfully started MPD Monitor Dec 05 18:15:22 primo volumio[1070]: info: Successfully started MPD Monitor Dec 05 18:15:22 primo volumio[1070]: info: TidalConnect service started! Dec 05 18:15:23 primo volumio[1070]: info: TidalConnect service started! Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:23 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:23 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:23 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.179:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 18:15:23 primo volumio[1070]: info: Discovery: Getting this device information Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:23 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 18:15:23 primo volumio[1070]: verbose: New Socket.io Connection to 192.168.1.248:3000 from 192.168.1.241 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 18:15:23 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 18:15:23 primo volumio[1070]: info: TidalConnect service started! Dec 05 18:15:24 primo volumio[1070]: info: TidalConnect service started! Dec 05 18:15:24 primo volumio[1070]: info: TidalConnect service started! Dec 05 18:15:31 primo volumio[1070]: info: CALLMETHOD: audio_interface alsa_controller saveDSPOptions [object Object] Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveDSPOptions Dec 05 18:15:31 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:31 primo volumio[1070]: info: Successfully set DSP FIR Filter Type with value Fast Roll-Off for card 0 Dec 05 18:15:31 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:31 primo volumio[1070]: info: Successfully set DSP IIR Filter Select with value 70kHz for card 0 Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 18:15:31 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 18:15:31 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 18:15:31 primo volumio[1070]: xcb_connection_has_error() returned true Dec 05 18:15:31 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 18:15:45 primo volumio[1070]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 05 18:15:45 primo sudo[4699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo sudo[4699]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:45 primo sudo[4699]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:45 primo sudo[4704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 05 18:15:45 primo sudo[4704]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:45 primo systemd[1]: Stopping Music Player Daemon... Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:45 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:45 primo volumio[1070]: info: CoreCommandRouter::volumioGetState Dec 05 18:15:45 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:15:45 primo volumio[1070]: info: MPD Permissions set Dec 05 18:15:45 primo systemd[1]: mpd.service: Succeeded. Dec 05 18:15:45 primo systemd[1]: Stopped Music Player Daemon. Dec 05 18:15:45 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:45 primo systemd[1]: Starting Music Player Daemon... Dec 05 18:15:45 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:45 primo sudo[4721]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 18:15:45 primo sudo[4721]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:45 primo sudo[4721]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:46 primo volumio[1070]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 05 18:15:46 primo sudo[4732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo sudo[4732]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:46 primo sudo[4732]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:46 primo sudo[4736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 05 18:15:46 primo sudo[4736]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:46 primo volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 18:15:46 primo volumio[1070]: info: MPD Permissions set Dec 05 18:15:46 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:46 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:46 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:46 primo systemd[1]: mpd.service: Succeeded. Dec 05 18:15:46 primo systemd[1]: Stopped Music Player Daemon. Dec 05 18:15:46 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:46 primo systemd[1]: Starting Music Player Daemon... Dec 05 18:15:46 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:46 primo sudo[4754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 18:15:46 primo sudo[4754]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:46 primo sudo[4754]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:47 primo mpd[4763]: Dec 05 18:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 18:15:47 primo systemd[1]: Started Music Player Daemon. Dec 05 18:15:47 primo sudo[4736]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:47 primo sudo[4704]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:47 primo volumio[1070]: error: MPD error: The expression evaluated to a falsy value: Dec 05 18:15:47 primo volumio[1070]: assert.ok(self.idling) Dec 05 18:15:47 primo volumio[1070]: error: The expression evaluated to a falsy value: Dec 05 18:15:47 primo volumio[1070]: assert.ok(self.idling) Dec 05 18:15:47 primo volumio[1070]: error: updateQueue error: null Dec 05 18:15:50 primo sudo[4787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 18:15:50 primo sudo[4787]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:50 primo sudo[4790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 18:15:50 primo sudo[4790]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 18:15:50 primo systemd[1]: Stopping MPD Monitor Service... Dec 05 18:15:50 primo systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:50 primo systemd[1]: mpd_monitor.service: Succeeded. Dec 05 18:15:50 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:50 primo systemd[1]: Stopped MPD Monitor Service. Dec 05 18:15:50 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:50 primo systemd[1]: Started MPD Monitor Service. Dec 05 18:15:50 primo sudo[4787]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:50 primo systemd[1]: Stopping MPD Monitor Service... Dec 05 18:15:50 primo systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 05 18:15:50 primo systemd[1]: mpd_monitor.service: Succeeded. Dec 05 18:15:50 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 18:15:50 primo systemd[1]: Stopped MPD Monitor Service. Dec 05 18:15:50 primo volumio[1070]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 18:15:50 primo systemd[1]: Started MPD Monitor Service. Dec 05 18:15:50 primo sudo[4790]: pam_unix(sudo:session): session closed for user root Dec 05 18:15:50 primo mpd_monitor.sh[4803]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 18:15:50 primo volumio[1070]: info: Successfully started MPD Monitor Dec 05 18:15:50 primo volumio[1070]: info: Successfully started MPD Monitor Dec 05 18:16:21 primo volumio[1070]: info: CorePlayQueue::getTrack 0 Dec 05 18:16:21 primo volumio[1070]: info: CorePlayQueue::getTrack 1 Dec 05 18:16:21 primo volumio[1070]: info: Prefetching next song Dec 05 18:16:21 primo volumio[1070]: info: [1764958581215] ControllerTidal::prefetch Dec 05 18:16:21 primo volumio[1070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 18:16:21 primo volumio[1070]: TypeError: Cannot read property 'highestSoundQuality' of undefined Dec 05 18:16:21 primo volumio[1070]: at ControllerTidal.getProperSoundQuality (/myvolumio/plugins/music_service/tidal/tidal_real:1:19583) Dec 05 18:16:21 primo volumio[1070]: at ControllerTidal.prefetch (/myvolumio/plugins/music_service/tidal/tidal_real:1:20287) Dec 05 18:16:21 primo volumio[1070]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Dec 05 18:16:21 primo volumio[1070]: at listOnTimeout (internal/timers.js:557:17) Dec 05 18:16:21 primo volumio[1070]: at processTimers (internal/timers.js:500:7) Dec 05 18:16:21 primo volumio[1070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 18:16:21 primo sudo[4914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-05 18:15 Dec 05 18:16:21 primo sudo[4914]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:17:52 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="3b20d491450ccf59e0e519a97572dc60"