-- Logs begin at Tue 2025-10-21 07:36:02 JST, end at Sat 2025-10-25 08:07:56 JST. -- Oct 25 08:06:29 rivo ntpd[8354]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 25 08:06:42 rivo ntpd[8354]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 25 08:06:43 rivo ntpd[8354]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 25 08:06:48 rivo ntpd[8354]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 25 08:06:57 rivo volumio[21095]: info: MCU Signalled Sleep Mode Disabled Oct 25 08:06:57 rivo sudo[20687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 25 08:06:57 rivo sudo[20687]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:57 rivo sudo[20687]: pam_unix(sudo:session): session closed for user root Oct 25 08:06:57 rivo sudo[20690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 25 08:06:57 rivo sudo[20690]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:57 rivo sudo[20690]: pam_unix(sudo:session): session closed for user root Oct 25 08:06:58 rivo kernel: usb 1-1.4.2: new high-speed USB device number 54 using xhci-hcd Oct 25 08:06:58 rivo kernel: usb 1-1.4.2: New USB device found, idVendor=1d9e, idProduct=0011 Oct 25 08:06:58 rivo kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 25 08:06:58 rivo kernel: usb 1-1.4.2: Product: Interface ITF-USBDAC Oct 25 08:06:58 rivo kernel: usb 1-1.4.2: Manufacturer: Interface Corporation Oct 25 08:06:58 rivo kernel: hid-generic 0003:1D9E:0011.00AB: hiddev0,hidraw0: USB HID v1.10 Device [Interface Corporation Interface ITF-USBDAC] on usb-xhci-hcd.0.auto-1.4.2/input2 Oct 25 08:06:58 rivo systemd-udevd[20692]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Oct 25 08:06:58 rivo volumio[21095]: info: Oct 25 08:06:58 rivo volumio[21095]: ---------------------------- USB Audio Device Attached Oct 25 08:06:58 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 25 08:06:59 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::Close All Modals sent Oct 25 08:06:59 rivo volumio[21095]: info: Preparing to save Alsa Options, stopping services first Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioPause Oct 25 08:06:59 rivo volumio[21095]: info: CoreStateMachine::pause Oct 25 08:06:59 rivo volumio[21095]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Interface ITF-USBDAC","alsacard":"ITFUSBDAC"},"i2s":false} Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 08:06:59 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:06:59 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 08:06:59 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:06:59 rivo volumio[21095]: info: Setting mixer PCM for card Interface ITF-USBDAC Oct 25 08:06:59 rivo volumio[21095]: info: QobuzConnect: setDeactiveState invoked Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:06:59 rivo volumio[21095]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 25 08:06:59 rivo volumio[21095]: info: QobuzConnect: setDeactiveState invoked Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:06:59 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 08:06:59 rivo volumio[21095]: info: Updating Volume Controller Parameters: Device: 5 Name: Interface ITF-USBDAC Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 08:06:59 rivo volumio[21095]: info: Disabling external Volume Control Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 08:06:59 rivo volumio[21095]: info: Preparing to generate the ALSA configuration file Oct 25 08:06:59 rivo sudo[20717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 08:06:59 rivo sudo[20717]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:59 rivo sudo[20717]: pam_unix(sudo:session): session closed for user root Oct 25 08:06:59 rivo sudo[20722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 08:06:59 rivo sudo[20722]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:59 rivo sudo[20724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 08:06:59 rivo sudo[20724]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:59 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 08:06:59 rivo qobuz-connect[31453]: 20251025 08:06:59.586 [31453.31453] INFO SampleApp: Stopping Local configuration server Oct 25 08:06:59 rivo qobuz-connect[31453]: 20251025 08:06:59.597 [31453.31453] INFO SampleApp: shat down connection on UNIX socket Oct 25 08:06:59 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:06:59 rivo systemd[1]: qobuz-connect.service: Succeeded. Oct 25 08:06:59 rivo volumio[21095]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 25 08:06:59 rivo volumio[21095]: info: QobuzConnect: setDeactiveState invoked Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:06:59 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 08:06:59 rivo systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 08:06:59 rivo sudo[20722]: pam_unix(sudo:session): session closed for user root Oct 25 08:06:59 rivo sudo[20724]: pam_unix(sudo:session): session closed for user root Oct 25 08:06:59 rivo sudo[20729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 08:06:59 rivo sudo[20729]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:59 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 08:06:59 rivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 25 08:06:59 rivo systemd[1]: qobuz-connect.service: Succeeded. Oct 25 08:06:59 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 08:06:59 rivo systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 08:06:59 rivo sudo[20729]: pam_unix(sudo:session): session closed for user root Oct 25 08:06:59 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 08:06:59 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 25 08:06:59 rivo volumio[21095]: info: Asound.conf file unchanged, so no further update is needed Oct 25 08:06:59 rivo volumio[21095]: info: Output device has changed, restarting MPD Oct 25 08:06:59 rivo sudo[20743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 08:06:59 rivo sudo[20743]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:59 rivo volumio[21095]: info: QobuzConnect: setDeactiveState invoked Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:06:59 rivo volumio[21095]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 25 08:06:59 rivo volumio[21095]: info: QobuzConnect: setDeactiveState invoked Oct 25 08:06:59 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:06:59 rivo sudo[20743]: pam_unix(sudo:session): session closed for user root Oct 25 08:06:59 rivo sudo[20746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 08:06:59 rivo sudo[20746]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:06:59 rivo systemd[1]: Stopping Music Player Daemon... Oct 25 08:06:59 rivo systemd[1]: mpd.service: Succeeded. Oct 25 08:06:59 rivo systemd[1]: Stopped Music Player Daemon. Oct 25 08:06:59 rivo sudo[20753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 08:06:59 rivo systemd[1]: Starting Music Player Daemon... Oct 25 08:06:59 rivo sudo[20753]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo sudo[20753]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo sudo[20760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 08:07:00 rivo sudo[20760]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo sudo[20761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 08:07:00 rivo sudo[20761]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: Not Reporting Auto name since its the default one Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 25 08:07:00 rivo sudo[20761]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 08:07:00 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:07:00 rivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 25 08:07:00 rivo systemd[1]: qobuz-connect.service: Succeeded. Oct 25 08:07:00 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:07:00 rivo systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 08:07:00 rivo sudo[20760]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:07:00 rivo volumio[21095]: info: MPD Permissions set Oct 25 08:07:00 rivo volumio[21095]: info: VolumeController:: Volume=46 Mute =false Oct 25 08:07:00 rivo volumio[21095]: info: CoreStateMachine::pushState Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::volumioPushState Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:00 rivo sudo[20768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 08:07:00 rivo sudo[20768]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo sudo[20755]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 08:07:00 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 08:07:00 rivo volumio[21095]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 25 08:07:00 rivo volumio[21095]: info: Updating RAAT Signal Path Oct 25 08:07:00 rivo volumio[21095]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 25 08:07:00 rivo volumio[21095]: info: Executing endpoint qc_getconfig Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo sudo[20755]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 25 08:07:00 rivo sudo[20755]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo systemd[1]: qobuz-connect.service: Succeeded. Oct 25 08:07:00 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 08:07:00 rivo systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 08:07:00 rivo sudo[20768]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 25 08:07:00 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 08:07:00 rivo sudo[20786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 25 08:07:00 rivo sudo[20786]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo sudo[20786]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 08:07:00 rivo volumio[21095]: info: Not Reporting Auto name since its the default one Oct 25 08:07:00 rivo volumio[21095]: info: Executing endpoint qc_getconfig Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.447 [20777.20777] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 25 08:07:00 rivo volumio[21095]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 25 08:07:00 rivo volumio[21095]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 25 08:07:00 rivo sudo[20792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.454 [20777.20777] INFO VolumeManager: [0xab63c240]: Setting new playback volume: 75 Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.454 [20777.20777] INFO VolumeManager: [0xab63c240]: Setting new mute state: 0 Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.454 [20777.20777] INFO QobuzConnect: [0xab63cc10]: Client initialized! Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.454 [20777.20777] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 25 08:07:00 rivo sudo[20792]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.473 [20777.20777] INFO LocalConfigManager: [0xab63bb20]: Starting Local Configuration server Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.473 [20777.20777] INFO SampleApp: Starting Local configuration server Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.474 [20777.20777] INFO SampleApp: Connected to UNIX socket client 0xab631ed8 Oct 25 08:07:00 rivo systemd[1]: Stopping RAAT DAEMON... Oct 25 08:07:00 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 25 08:07:00 rivo systemd[1]: raat-daemon.service: Succeeded. Oct 25 08:07:00 rivo systemd[1]: Stopped RAAT DAEMON. Oct 25 08:07:00 rivo systemd[1]: Started RAAT DAEMON. Oct 25 08:07:00 rivo sudo[20792]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo volumio[21095]: xcb_connection_has_error() returned true Oct 25 08:07:00 rivo qobuz-connect[20777]: 20251025 08:07:00.597 [20777.20777] INFO SampleApp: Playback volume changed: 75 Oct 25 08:07:00 rivo sudo[20805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 08:07:00 rivo sudo[20805]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo volumio[21095]: info: Raat Daemon started successfully Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:00 rivo volumio[21095]: info: Executing endpoint restartRAATSocket Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 25 08:07:00 rivo sudo[20805]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo sudo[20818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 25 08:07:00 rivo sudo[20818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:00 rivo systemd[1]: Stopping RAAT DAEMON... Oct 25 08:07:00 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 25 08:07:00 rivo systemd[1]: raat-daemon.service: Succeeded. Oct 25 08:07:00 rivo systemd[1]: Stopped RAAT DAEMON. Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 08:07:00 rivo volumio[21095]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 08:07:00 rivo systemd[1]: Started RAAT DAEMON. Oct 25 08:07:00 rivo sudo[20818]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:00 rivo volumio[21095]: info: Raat Daemon started successfully Oct 25 08:07:00 rivo volumio[21095]: info: Executing endpoint restartRAATSocket Oct 25 08:07:00 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 25 08:07:02 rivo mpd[20779]: Oct 25 08:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 08:07:02 rivo systemd[1]: Started Music Player Daemon. Oct 25 08:07:02 rivo sudo[20746]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:02 rivo volumio[21095]: error: updateQueue error: null Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 25 08:07:03 rivo volumio[21095]: info: CoreStateMachine::getcurrentVolume Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 08:07:03 rivo volumio[21095]: info: RAAT: Requesting Headphone Status Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 25 08:07:03 rivo volumio[21095]: info: MCU Signalled Headphone Mode Disabled Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 25 08:07:03 rivo volumio[21095]: info: VolumeController:: Volume=46 Mute =false Oct 25 08:07:03 rivo volumio[21095]: info: CoreStateMachine::pushState Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioPushState Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:03 rivo volumio[21095]: info: CoreStateMachine::updateTrackBlock Oct 25 08:07:03 rivo volumio[21095]: info: CorePlayQueue::getTrackBlock Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 08:07:03 rivo volumio[21095]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 25 08:07:03 rivo volumio[21095]: info: Updating RAAT Signal Path Oct 25 08:07:03 rivo volumio[21095]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 25 08:07:03 rivo volumio[21095]: info: RAAT: Requesting Headphone Status Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 25 08:07:03 rivo volumio[21095]: info: MCU Signalled Headphone Mode Disabled Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 25 08:07:03 rivo volumio[21095]: info: VolumeController:: Volume=46 Mute =false Oct 25 08:07:03 rivo volumio[21095]: info: CoreStateMachine::pushState Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioPushState Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:03 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:03 rivo volumio[21095]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 25 08:07:03 rivo volumio[21095]: info: Updating RAAT Signal Path Oct 25 08:07:03 rivo volumio[21095]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 25 08:07:34 rivo ntpd[8354]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 25 08:07:48 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 08:07:48 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 08:07:48 rivo volumio[21095]: info: Discovery: Getting this device information Oct 25 08:07:48 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:48 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 08:07:48 rivo volumio[21095]: verbose: New Socket.io Connection to 192.168.1.15:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 25 08:07:48 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:48 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 25 08:07:48 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 25 08:07:48 rivo ntpd[8354]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 25 08:07:49 rivo ntpd[8354]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 25 08:07:51 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:52 rivo volumio[21095]: verbose: New Socket.io Connection to 192.168.1.15:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 25 08:07:52 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:52 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 25 08:07:52 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 25 08:07:52 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:52 rivo ntpd[8354]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 25 08:07:53 rivo sudo[20985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 08:07:53 rivo sudo[20985]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:53 rivo sudo[20985]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:53 rivo sudo[20988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 08:07:53 rivo sudo[20988]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:53 rivo sudo[20988]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:53 rivo volumio[21095]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 12; L-51A Build/SKQ1.211103.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 08:07:53 rivo volumio[21095]: info: Discovery: Getting this device information Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 08:07:53 rivo sudo[20993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 08:07:53 rivo sudo[20993]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:53 rivo sudo[20993]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:53 rivo sudo[20996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 08:07:53 rivo sudo[20996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 08:07:53 rivo sudo[20996]: pam_unix(sudo:session): session closed for user root Oct 25 08:07:53 rivo volumio[21095]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 12; L-51A Build/SKQ1.211103.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 08:07:53 rivo volumio[21095]: info: Received Get System Info Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 08:07:53 rivo volumio[21095]: info: Discovery: Getting this device information Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:53 rivo volumio[21095]: info: Listing playlists Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 08:07:53 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 08:07:55 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 25 08:07:55 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 08:07:55 rivo volumio[21095]: info: Received Get System Info Oct 25 08:07:55 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 08:07:55 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 08:07:55 rivo volumio[21095]: info: Discovery: Getting this device information Oct 25 08:07:55 rivo volumio[21095]: info: CoreCommandRouter::volumioGetState Oct 25 08:07:55 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 08:07:55 rivo volumio[21095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 08:07:55 rivo volumio[21095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 08:07:55 rivo volumio[21095]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Oct 25 08:07:55 rivo volumio[21095]: code: 'auth/network-request-failed', Oct 25 08:07:55 rivo volumio[21095]: a: null Oct 25 08:07:55 rivo volumio[21095]: } Oct 25 08:07:55 rivo volumio[21095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 08:07:56 rivo ntpd[8354]: ntpd exiting on signal 15 (Terminated) Oct 25 08:07:56 rivo systemd[1]: Stopping Network Time Service... Oct 25 08:07:56 rivo systemd[1]: ntp.service: Succeeded. Oct 25 08:07:56 rivo systemd[1]: Stopped Network Time Service. Oct 25 08:07:56 rivo systemd[1]: Starting Network Time Service... Oct 25 08:07:56 rivo ntpd[21072]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 25 08:07:56 rivo ntpd[21072]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 25 08:07:56 rivo systemd[1]: Started Network Time Service. Oct 25 08:07:56 rivo ntpd[21087]: proto: precision = 1.208 usec (-20) Oct 25 08:07:56 rivo ntpd[21087]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 25 08:07:56 rivo ntpd[21087]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 25 08:07:56 rivo ntpd[21087]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1032 days ago Oct 25 08:07:56 rivo ntpd[21087]: Listen and drop on 0 v6wildcard [::]:123 Oct 25 08:07:56 rivo ntpd[21087]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 25 08:07:56 rivo ntpd[21087]: Listen normally on 2 lo 127.0.0.1:123 Oct 25 08:07:56 rivo ntpd[21087]: Listen normally on 3 eth0 192.168.1.15:123 Oct 25 08:07:56 rivo ntpd[21087]: Listening on routing socket on fd #20 for interface updates Oct 25 08:07:56 rivo ntpd[21087]: kernel reports TIME_ERROR: 0x6041: Clock Unsynchronized Oct 25 08:07:56 rivo ntpd[21087]: kernel reports TIME_ERROR: 0x6041: Clock Unsynchronized Oct 25 08:07:56 rivo sudo[21090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 08:06 Oct 25 08:07:56 rivo sudo[21090]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:43:09 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="fc7d09bc07ffd188bff4808d8f79c746"