-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Tue 2026-02-24 01:08:23 +07. -- Feb 24 01:07:11 volumio--no.2 ntpd[806]: 202.28.117.7 local addr 192.168.1.40 -> Feb 24 01:07:17 volumio--no.2 go-librespot[1915]: time="2026-02-24T01:07:17+07:00" level=trace msg="sent dealer ping" Feb 24 01:07:17 volumio--no.2 go-librespot[1915]: time="2026-02-24T01:07:17+07:00" level=trace msg="received dealer pong" Feb 24 01:07:27 volumio--no.2 volumiologrotate[549]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 24 01:07:27 volumio--no.2 volumiologrotate[549]: ls: cannot access 'NO.2': No such file or directory Feb 24 01:07:47 volumio--no.2 go-librespot[1915]: time="2026-02-24T01:07:47+07:00" level=trace msg="sent dealer ping" Feb 24 01:07:47 volumio--no.2 go-librespot[1915]: time="2026-02-24T01:07:47+07:00" level=trace msg="received dealer pong" Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Preparing to save Alsa Options, stopping services first Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioPause Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreStateMachine::pause Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Saving Audio Output to: {"output_device":{"value":"2","label":"I-Sabre Q2M DAC"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Setting mixer Digital for card I-Sabre Q2M DAC Feb 24 01:07:51 volumio--no.2 volumio[846]: info: QobuzConnect: setDeactiveState invoked Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Relinquishing Volumio State Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 volumio[846]: verbose: UNSET VOLATILE: Service: tidalconnect Feb 24 01:07:51 volumio--no.2 volumio[846]: info: unSetVolatile called Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Relinquishing Volumio State to another service Feb 24 01:07:51 volumio--no.2 vtcs[3090]: [2026-02-24 01:07:51.346] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 24 01:07:51 volumio--no.2 vtcs[3090]: [2026-02-24 01:07:51.346] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 24 01:07:51 volumio--no.2 vtcs[3090]: [close:85] Entering Feb 24 01:07:51 volumio--no.2 vtcs[3090]: [close:100] Exiting Feb 24 01:07:51 volumio--no.2 sudo[7475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 01:07:51 volumio--no.2 sudo[7475]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:51 volumio--no.2 systemd[1]: Stopping Volumio Tidal Connect Service... Feb 24 01:07:51 volumio--no.2 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 24 01:07:51 volumio--no.2 systemd[1]: vtcs.service: Succeeded. Feb 24 01:07:51 volumio--no.2 systemd[1]: Stopped Volumio Tidal Connect Service. Feb 24 01:07:51 volumio--no.2 sudo[7475]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Updating Volume Controller Parameters: Device: 2 Name: I-Sabre Q2M DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Disabling external Volume Control Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Preparing to generate the ALSA configuration file Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 sudo[7482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 01:07:51 volumio--no.2 sudo[7482]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:51 volumio--no.2 sudo[7482]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:51 volumio--no.2 volumio[846]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 24 01:07:51 volumio--no.2 volumio[846]: info: Reading ALSA contributions from plugins. Feb 24 01:07:51 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 01:07:51 volumio--no.2 sudo[7489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 01:07:51 volumio--no.2 sudo[7489]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:51 volumio--no.2 sudo[7489]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:51 volumio--no.2 sudo[7492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 01:07:51 volumio--no.2 sudo[7492]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 24 01:07:51 volumio--no.2 qobuz-connect[3095]: 20260224 01:07:51.736 [3095.3095] INFO SampleApp: Stopping Local configuration server Feb 24 01:07:51 volumio--no.2 qobuz-connect[3095]: 20260224 01:07:51.746 [3095.3095] INFO SampleApp: shat down connection on UNIX socket Feb 24 01:07:51 volumio--no.2 systemd[1]: qobuz-connect.service: Succeeded. Feb 24 01:07:51 volumio--no.2 systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:51 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 24 01:07:51 volumio--no.2 systemd[1]: Started Volumio Qobuz Connect Service. Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 01:07:51 volumio--no.2 sudo[7492]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:51 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 01:07:51 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 01:07:51 volumio--no.2 volumio[846]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 01:07:51 volumio--no.2 volumio[846]: info: QobuzConnect: setDeactiveState invoked Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:51 volumio--no.2 volumio[846]: info: VolumeController:: Volume=76 Mute =true Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreStateMachine::pushState Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioPushState Feb 24 01:07:51 volumio--no.2 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Feb 24 01:07:51 volumio--no.2 volumio[846]: info: MRS: Pushing multiroomSync output Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:51 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:51 volumio--no.2 volumio5-onboarding[1066]: time=2026-02-24T01:07:51.952+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:44458 @ 0x19347e0" state=STATUS_PAUSED positionMs=317 volume=76 Feb 24 01:07:51 volumio--no.2 volumio5-onboarding[1066]: time=2026-02-24T01:07:51.953+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:44458 @ 0x19347e0" id=qobuz://song/9043753 title="Punta Del Soul" Feb 24 01:07:52 volumio--no.2 volumio[846]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Executing endpoint qc_getconfig Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.082 [7496.7496] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 01:07:52 volumio--no.2 volumio[846]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 01:07:52 volumio--no.2 volumio[846]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Asound.conf file unchanged, so no further update is needed Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Output device has changed, restarting MPD Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.089 [7496.7496] INFO VolumeManager: [0x2467328]: Setting new playback volume: 75 Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.089 [7496.7496] INFO VolumeManager: [0x2467328]: Setting new mute state: 0 Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.089 [7496.7496] INFO QobuzConnect: [0x2467cf8]: Client initialized! Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.089 [7496.7496] INFO SampleApp: Starting Avahi advertising, name: Volumio no.2, service name: _qobuz-connect._tcp Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.105 [7496.7496] INFO LocalConfigManager: [0x2466c08]: Starting Local Configuration server Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.105 [7496.7496] INFO SampleApp: Starting Local configuration server Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.106 [7496.7496] INFO SampleApp: Connected to UNIX socket client 0x245cf60 Feb 24 01:07:52 volumio--no.2 sudo[7507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 01:07:52 volumio--no.2 sudo[7507]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Output device has changed, restarting Shairport Sync Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 01:07:52 volumio--no.2 sudo[7507]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:52 volumio--no.2 sudo[7511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 01:07:52 volumio--no.2 sudo[7511]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 systemd[1]: Stopping Music Player Daemon... Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.232 [7496.7496] INFO SampleApp: Playback volume changed: 75 Feb 24 01:07:52 volumio--no.2 systemd[1]: mpd.service: Succeeded. Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 01:07:52 volumio--no.2 systemd[1]: Stopped Music Player Daemon. Feb 24 01:07:52 volumio--no.2 volumio[846]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 01:07:52 volumio--no.2 systemd[1]: Starting Music Player Daemon... Feb 24 01:07:52 volumio--no.2 volumio[846]: info: QobuzConnect: setDeactiveState invoked Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:52 volumio--no.2 sudo[7521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 01:07:52 volumio--no.2 sudo[7521]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 sudo[7525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 01:07:52 volumio--no.2 sudo[7517]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 01:07:52 volumio--no.2 sudo[7517]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 sudo[7525]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 sudo[7521]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:52 volumio--no.2 sudo[7517]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:52 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 01:07:52 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 01:07:52 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 01:07:52 volumio--no.2 sudo[7534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 01:07:52 volumio--no.2 sudo[7534]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:52 volumio--no.2 volumio[846]: info: MPD Permissions set Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 01:07:52 volumio--no.2 sudo[7534]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:52 volumio--no.2 sudo[7525]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:52 volumio--no.2 sudo[7540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 01:07:52 volumio--no.2 sudo[7540]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Starting Shairport Sync Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.648 [7496.7496] INFO SampleApp: Stopping Local configuration server Feb 24 01:07:52 volumio--no.2 systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 24 01:07:52 volumio--no.2 qobuz-connect[7496]: 20260224 01:07:52.659 [7496.7496] INFO SampleApp: shat down connection on UNIX socket Feb 24 01:07:52 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 01:07:52 volumio--no.2 systemd[1]: qobuz-connect.service: Succeeded. Feb 24 01:07:52 volumio--no.2 systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 24 01:07:52 volumio--no.2 volumio[846]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 01:07:52 volumio--no.2 volumio[846]: info: QobuzConnect: setDeactiveState invoked Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:52 volumio--no.2 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 01:07:52 volumio--no.2 sudo[7544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 01:07:52 volumio--no.2 sudo[7544]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:52 volumio--no.2 systemd[1]: Started Volumio Qobuz Connect Service. Feb 24 01:07:52 volumio--no.2 sudo[7540]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:52 volumio--no.2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Executing endpoint qc_getconfig Feb 24 01:07:52 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 01:07:52 volumio--no.2 sudo[7544]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.875 [7545.7545] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.883 [7545.7545] INFO VolumeManager: [0xec0328]: Setting new playback volume: 75 Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.883 [7545.7545] INFO VolumeManager: [0xec0328]: Setting new mute state: 0 Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.883 [7545.7545] INFO QobuzConnect: [0xec0cf8]: Client initialized! Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.883 [7545.7545] INFO SampleApp: Starting Avahi advertising, name: Volumio no.2, service name: _qobuz-connect._tcp Feb 24 01:07:52 volumio--no.2 volumio[846]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 01:07:52 volumio--no.2 volumio[846]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 01:07:52 volumio--no.2 volumio[846]: info: Shairport-Sync Started Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.899 [7545.7545] INFO LocalConfigManager: [0xebfc08]: Starting Local Configuration server Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.899 [7545.7545] INFO SampleApp: Starting Local configuration server Feb 24 01:07:52 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:52.900 [7545.7545] INFO SampleApp: Connected to UNIX socket client 0xeb5f60 Feb 24 01:07:53 volumio--no.2 qobuz-connect[7545]: 20260224 01:07:53.025 [7545.7545] INFO SampleApp: Playback volume changed: 75 Feb 24 01:07:53 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:53 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:53 volumio--no.2 mpd[7533]: Feb 24 01:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 01:07:53 volumio--no.2 systemd[1]: Started Music Player Daemon. Feb 24 01:07:53 volumio--no.2 sudo[7511]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:53 volumio--no.2 volumio[846]: error: updateQueue error: null Feb 24 01:07:54 volumio--no.2 volumio[846]: info: TidalConnect service stoped! Feb 24 01:07:54 volumio--no.2 volumio[846]: info: TidalConnect service stoped! Feb 24 01:07:54 volumio--no.2 volumio[846]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 01:07:54 volumio--no.2 volumio[846]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 01:07:54 volumio--no.2 sudo[7567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 01:07:54 volumio--no.2 sudo[7567]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:54 volumio--no.2 systemd[1]: Started Volumio Tidal Connect Service. Feb 24 01:07:54 volumio--no.2 sudo[7567]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:55 volumio--no.2 volumio[846]: info: Executing endpoint tc_getconfig Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 24 01:07:55 volumio--no.2 vtcs[7569]: STARTING TidalConnect services, version: 1.6.1 Feb 24 01:07:55 volumio--no.2 vtcs[7569]: STARTED TidalConnect services. Feb 24 01:07:55 volumio--no.2 volumio[846]: info: Executing endpoint tc_connect Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 24 01:07:55 volumio--no.2 volumio[846]: info: Connecting to TidalConnect Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::servicePushState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::servicePushState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Feb 24 01:07:55 volumio--no.2 volumio[846]: info: TidalConnect service stoped! Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreStateMachine::getcurrentVolume Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: VolumeController:: Volume=76 Mute =true Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreStateMachine::pushState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioPushState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Feb 24 01:07:55 volumio--no.2 volumio[846]: info: MRS: Pushing multiroomSync output Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreStateMachine::updateTrackBlock Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrackBlock Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 01:07:55 volumio--no.2 volumio5-onboarding[1066]: time=2026-02-24T01:07:55.668+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:44458 @ 0x19347e0" state=STATUS_PAUSED positionMs=317 volume=76 Feb 24 01:07:55 volumio--no.2 volumio5-onboarding[1066]: time=2026-02-24T01:07:55.669+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:44458 @ 0x19347e0" id=qobuz://song/9043753 title="Punta Del Soul" Feb 24 01:07:55 volumio--no.2 volumio[846]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: TidalConnect service stoped! Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: VolumeController:: Volume=76 Mute =true Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreStateMachine::pushState Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Feb 24 01:07:55 volumio--no.2 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioPushState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Feb 24 01:07:55 volumio--no.2 volumio[846]: info: MRS: Pushing multiroomSync output Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetState Feb 24 01:07:55 volumio--no.2 volumio[846]: info: CorePlayQueue::getTrack 0 Feb 24 01:07:55 volumio--no.2 volumio5-onboarding[1066]: time=2026-02-24T01:07:55.827+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:44458 @ 0x19347e0" state=STATUS_PAUSED positionMs=317 volume=76 Feb 24 01:07:55 volumio--no.2 volumio5-onboarding[1066]: time=2026-02-24T01:07:55.828+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:44458 @ 0x19347e0" id=qobuz://song/9043753 title="Punta Del Soul" Feb 24 01:07:55 volumio--no.2 volumio[846]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Feb 24 01:07:55 volumio--no.2 volumio[846]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 01:07:55 volumio--no.2 volumio[846]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 01:07:55 volumio--no.2 sudo[7601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 01:07:55 volumio--no.2 sudo[7601]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 01:07:55 volumio--no.2 sudo[7601]: pam_unix(sudo:session): session closed for user root Feb 24 01:07:57 volumio--no.2 volumio[846]: error: error Feb 24 01:07:57 volumio--no.2 volumio[846]: info: TidalConnect service started! Feb 24 01:07:58 volumio--no.2 volumio[846]: info: TidalConnect service started! Feb 24 01:08:15 volumio--no.2 volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 01:08:15 volumio--no.2 volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Retrieving Cloud Streaming UI Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Getting Tidal Cloud Configuration Feb 24 01:08:15 volumio--no.2 volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Getting Qobuz Cloud Configuration Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Asking plugin for UI Config Feb 24 01:08:15 volumio--no.2 volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Getting Spotify Cloud Configuration Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Asking plugin for UI Config Feb 24 01:08:15 volumio--no.2 volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Saving Spotify Acccount Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Got it Feb 24 01:08:15 volumio--no.2 volumio[846]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Got Tidal Cloud Configuration Feb 24 01:08:15 volumio--no.2 volumio[846]: info: Got it Feb 24 01:08:15 volumio--no.2 volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 01:08:16 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 24 01:08:17 volumio--no.2 go-librespot[1915]: time="2026-02-24T01:08:17+07:00" level=trace msg="sent dealer ping" Feb 24 01:08:17 volumio--no.2 go-librespot[1915]: time="2026-02-24T01:08:17+07:00" level=trace msg="received dealer pong" Feb 24 01:08:20 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 01:08:22 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 24 01:08:22 volumio--no.2 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 24 01:08:22 volumio--no.2 volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 01:08:22 volumio--no.2 volumio[846]: Error: getaddrinfo -3007 Feb 24 01:08:22 volumio--no.2 volumio[846]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Feb 24 01:08:22 volumio--no.2 volumio[846]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Feb 24 01:08:22 volumio--no.2 volumio[846]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Feb 24 01:08:22 volumio--no.2 volumio[846]: code: -3007, Feb 24 01:08:22 volumio--no.2 volumio[846]: errno: -3007, Feb 24 01:08:22 volumio--no.2 volumio[846]: syscall: 'getaddrinfo' Feb 24 01:08:22 volumio--no.2 volumio[846]: } Feb 24 01:08:22 volumio--no.2 volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 01:08:23 volumio--no.2 sudo[7659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 01:07 Feb 24 01:08:23 volumio--no.2 sudo[7659]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"