-- Logs begin at Fri 2025-12-12 02:47:33 JST, end at Tue 2025-12-30 17:04:33 JST. -- Dec 30 17:03:02 rivoplus ntpd[3291]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 30 17:03:18 rivoplus volumio[3108]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:18 rivoplus volumio[3108]: info: Restoring Previous Volume level: 100 false false Dec 30 17:03:18 rivoplus volumio[3108]: info: Output device has changed, restarting MPD Dec 30 17:03:18 rivoplus sudo[6403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 17:03:18 rivoplus sudo[6403]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:18 rivoplus volumio[3108]: info: Output device has changed, restarting Shairport Sync Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:03:18 rivoplus sudo[6403]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:18 rivoplus sudo[6406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 17:03:18 rivoplus sudo[6406]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:18 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 30 17:03:18 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 17:03:18 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 17:03:18 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:18 rivoplus volumio[3108]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 17:03:18 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:18 rivoplus volumio[3108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:18 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 17:03:18 rivoplus volumio[3108]: info: QobuzConnect: setDeactiveState invoked Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:18 rivoplus volumio[3108]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:18 rivoplus vtcs[6190]: [2025-12-30 17:03:18.910] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 30 17:03:18 rivoplus vtcs[6190]: [2025-12-30 17:03:18.910] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 30 17:03:18 rivoplus vtcs[6190]: [2025-12-30 17:03:18.910] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 30 17:03:18 rivoplus volumio[3108]: info: Volume configurations have been set Dec 30 17:03:18 rivoplus volumio[3108]: info: QobuzConnect: setDeactiveState invoked Dec 30 17:03:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:18 rivoplus volumio[3108]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 30 17:03:18 rivoplus sudo[6421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 17:03:18 rivoplus sudo[6421]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:18 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 30 17:03:18 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:19 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 30 17:03:19 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 30 17:03:19 rivoplus sudo[6421]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus sudo[6426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 17:03:19 rivoplus sudo[6412]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 17:03:19 rivoplus sudo[6412]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus sudo[6426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus sudo[6412]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus sudo[6426]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 17:03:19 rivoplus volumio[3108]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 17:03:19 rivoplus volumio[3108]: info: Disabling external Volume Control Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:19 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:19 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:19 rivoplus sudo[6438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 17:03:19 rivoplus sudo[6438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus sudo[6441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 17:03:19 rivoplus sudo[6441]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus sudo[6438]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus sudo[6441]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus sudo[6449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 17:03:19 rivoplus sudo[6449]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus sudo[6449]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:19 rivoplus sudo[6455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 17:03:19 rivoplus sudo[6455]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:19 rivoplus sudo[6456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 17:03:19 rivoplus sudo[6456]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:19 rivoplus qobuz-connect[6144]: 20251230 17:03:19.411 [6144.6144] INFO SampleApp: Stopping Local configuration server Dec 30 17:03:19 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 30 17:03:19 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:19 rivoplus qobuz-connect[6144]: 20251230 17:03:19.422 [6144.6144] INFO SampleApp: shat down connection on UNIX socket Dec 30 17:03:19 rivoplus volumio[3108]: info: MPD Permissions set Dec 30 17:03:19 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 30 17:03:19 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 30 17:03:19 rivoplus sudo[6456]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 30 17:03:19 rivoplus sudo[6455]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: Not Reporting Auto name since its the default one Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:19 rivoplus sudo[6461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 17:03:19 rivoplus sudo[6461]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:19 rivoplus volumio[3108]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 30 17:03:19 rivoplus volumio[3108]: info: QobuzConnect: setDeactiveState invoked Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:19 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 30 17:03:19 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Dec 30 17:03:19 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 30 17:03:19 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 30 17:03:19 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus sudo[6461]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:19 rivoplus volumio[3108]: info: Not Reporting Auto name since its the default one Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:19 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 17:03:19 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 30 17:03:19 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:19 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 30 17:03:19 rivoplus sudo[6488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 30 17:03:19 rivoplus sudo[6488]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:19 rivoplus sudo[6488]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:20 rivoplus sudo[6492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 30 17:03:20 rivoplus sudo[6492]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:20 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:20 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 30 17:03:20 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:20 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 30 17:03:20 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 30 17:03:20 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:20 rivoplus volumio[3108]: info: Executing endpoint qc_getconfig Dec 30 17:03:20 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 30 17:03:20 rivoplus systemd[1]: Started RAAT DAEMON. Dec 30 17:03:20 rivoplus sudo[6492]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:20 rivoplus sudo[6495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 30 17:03:20 rivoplus sudo[6495]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:20 rivoplus sudo[6495]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.141 [6473.6473] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.148 [6473.6473] INFO VolumeManager: [0xab52c240]: Setting new playback volume: 75 Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.148 [6473.6473] INFO VolumeManager: [0xab52c240]: Setting new mute state: 0 Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.148 [6473.6473] INFO QobuzConnect: [0xab52cc10]: Client initialized! Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.148 [6473.6473] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 30 17:03:20 rivoplus volumio[3108]: info: Starting Shairport Sync Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.173 [6473.6473] INFO LocalConfigManager: [0xab52bb20]: Starting Local Configuration server Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.174 [6473.6473] INFO SampleApp: Starting Local configuration server Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.175 [6473.6473] INFO SampleApp: Connected to UNIX socket client 0xab521ed8 Dec 30 17:03:20 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:20 rivoplus volumio[3108]: info: Raat Daemon started successfully Dec 30 17:03:20 rivoplus sudo[6513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 17:03:20 rivoplus sudo[6513]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:20 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:20 rivoplus volumio[3108]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 30 17:03:20 rivoplus volumio[3108]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 30 17:03:20 rivoplus sudo[6505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 30 17:03:20 rivoplus sudo[6505]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:20 rivoplus volumio[3108]: info: Executing endpoint restartRAATSocket Dec 30 17:03:20 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 30 17:03:20 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 30 17:03:20 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 30 17:03:20 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 30 17:03:20 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 30 17:03:20 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:20 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 30 17:03:20 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 30 17:03:20 rivoplus qobuz-connect[6473]: 20251230 17:03:20.291 [6473.6473] INFO SampleApp: Playback volume changed: 75 Dec 30 17:03:20 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:20 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:20 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:20 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:20 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:20 rivoplus volumio[3108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 30 17:03:20 rivoplus systemd[1]: Started RAAT DAEMON. Dec 30 17:03:20 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 30 17:03:20 rivoplus sudo[6513]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:20 rivoplus sudo[6505]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:20 rivoplus volumio[3108]: info: Shairport-Sync Started Dec 30 17:03:20 rivoplus volumio[3108]: info: Raat Daemon started successfully Dec 30 17:03:20 rivoplus volumio[3108]: info: Executing endpoint restartRAATSocket Dec 30 17:03:20 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 30 17:03:20 rivoplus volumio[3108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 30 17:03:21 rivoplus mpd[6434]: Dec 30 17:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 17:03:21 rivoplus systemd[1]: Started Music Player Daemon. Dec 30 17:03:21 rivoplus sudo[6406]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:21 rivoplus volumio[3108]: error: updateQueue error: null Dec 30 17:03:22 rivoplus volumio[3108]: info: TidalConnect service stoped! Dec 30 17:03:22 rivoplus volumio[3108]: info: TidalConnect service stoped! Dec 30 17:03:22 rivoplus volumio[3108]: info: TidalConnect service stoped! Dec 30 17:03:22 rivoplus volumio[3108]: info: TidalConnect service stoped! Dec 30 17:03:22 rivoplus volumio[3108]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 17:03:22 rivoplus volumio[3108]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 17:03:22 rivoplus volumio[3108]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 17:03:22 rivoplus volumio[3108]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 17:03:22 rivoplus sudo[6555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 17:03:22 rivoplus sudo[6555]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:22 rivoplus sudo[6558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 17:03:22 rivoplus sudo[6558]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:22 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 30 17:03:22 rivoplus sudo[6555]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:22 rivoplus sudo[6558]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:22 rivoplus volumio[3108]: info: Executing endpoint tc_getconfig Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 30 17:03:22 rivoplus vtcs[6560]: STARTING TidalConnect services, version: 1.5.2.56 Dec 30 17:03:22 rivoplus vtcs[6560]: STARTED TidalConnect services. Dec 30 17:03:22 rivoplus volumio[3108]: info: Executing endpoint tc_connect Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 30 17:03:22 rivoplus volumio[3108]: info: Connecting to TidalConnect Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:22 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Dec 30 17:03:22 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 30 17:03:22 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService stop Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::stPlaybackTimer Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:22 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Dec 30 17:03:22 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 30 17:03:22 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService stop Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:22 rivoplus volumio[3108]: info: No code Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:22 rivoplus go-librespot[4486]: time="2025-12-30T17:03:22+09:00" level=trace msg="received accesspoint ping" Dec 30 17:03:22 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:23 rivoplus volumio[3108]: info: RAAT: Requesting Headphone Status Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 30 17:03:23 rivoplus go-librespot[4486]: time="2025-12-30T17:03:23+09:00" level=trace msg="received accesspoint pong ack" Dec 30 17:03:23 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:23 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:23 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:23 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:23 rivoplus volumio[3108]: info: RAAT: Requesting Headphone Status Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 30 17:03:23 rivoplus volumio[3108]: info: MCU Signalled Headphone Mode Disabled Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 17:03:23 rivoplus volumio[3108]: info: Reporting Headphone State: false Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:23 rivoplus go-librespot[4486]: time="2025-12-30T17:03:23+09:00" level=trace msg="sent dealer ping" Dec 30 17:03:23 rivoplus go-librespot[4486]: time="2025-12-30T17:03:23+09:00" level=trace msg="received dealer pong" Dec 30 17:03:23 rivoplus volumio[3108]: info: VolumeController::SetAlsaVolume100 Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:23 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:23 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:23 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:23 rivoplus volumio[3108]: info: MCU Signalled Playback Inactive Dec 30 17:03:23 rivoplus volumio[3108]: info: MCU Signalled Playback Active Dec 30 17:03:23 rivoplus volumio[3108]: info: MCU Signalled Headphone Mode Disabled Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 17:03:23 rivoplus volumio[3108]: info: Reporting Headphone State: false Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:23 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:23 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:24 rivoplus sudo[6581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 17:03:24 rivoplus sudo[6581]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:24 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 30 17:03:24 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:24 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 30 17:03:24 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:24 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 30 17:03:24 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:24 rivoplus systemd[1]: Started MPD Monitor Service. Dec 30 17:03:24 rivoplus sudo[6581]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:24 rivoplus volumio[3108]: info: Successfully started MPD Monitor Dec 30 17:03:24 rivoplus mpd_monitor.sh[6583]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 17:03:25 rivoplus volumio[3108]: info: TidalConnect service started! Dec 30 17:03:25 rivoplus volumio[3108]: info: TidalConnect service started! Dec 30 17:03:25 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:25.687+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.3.13:53863,00:00:00:00:00:00%01 @ 0x25e7da0" latency=6.756784ms platform=PLATFORM_IOS version=4.251222.0 Dec 30 17:03:25 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:25.688+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.3.13:53863,00:00:00:00:00:00%01 @ 0x25e7da0" latency=6.982296ms timeout=10s Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 17:03:25 rivoplus volumio[3108]: info: Received Get System Info Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:03:25 rivoplus volumio[3108]: info: Discovery: Getting this device information Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Dec 30 17:03:25 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:03:25 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 17:03:25 rivoplus volumio[3108]: amixer -c 1 info | grep "Comtrue-inc UAC2 Device" Dec 30 17:03:25 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:25 rivoplus volumio[3108]: Card hw:1 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Dec 30 17:03:25 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:25 rivoplus volumio[3108]: amixer -c 5 info | grep "SMSL USB AUDIO" Dec 30 17:03:26 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:26 rivoplus volumio[3108]: Card hw:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-xhci-hcd.0.auto-1.4.2, high speed' Dec 30 17:03:26 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:26 rivoplus volumio[3108]: amixer -c 5 info | grep "SMSL USB AUDIO" Dec 30 17:03:26 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:26 rivoplus volumio[3108]: Card hw:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-xhci-hcd.0.auto-1.4.2, high speed' Dec 30 17:03:26 rivoplus volumio[3108]: info: Received Get System Info Dec 30 17:03:26 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:03:26 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:03:26 rivoplus volumio[3108]: info: Discovery: Getting this device information Dec 30 17:03:26 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:26 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:03:26 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:26 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:26.227+09:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.3.13:53863,00:00:00:00:00:00%01 @ 0x25e7da0" latency=5.715247ms timeout=10s from=APP_PAGE_ROOT Dec 30 17:03:26 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:26.515+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 17:03:26 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:26.815+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 17:03:27 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:27.116+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 17:03:27 rivoplus sudo[6626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 17:03:27 rivoplus sudo[6626]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:27 rivoplus sudo[6626]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:27 rivoplus sudo[6629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 17:03:27 rivoplus sudo[6629]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:27 rivoplus sudo[6629]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:27 rivoplus volumio[3108]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 17:03:27 rivoplus sudo[6634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 17:03:27 rivoplus sudo[6634]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 30 17:03:27 rivoplus sudo[6634]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:27 rivoplus sudo[6638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 17:03:27 rivoplus sudo[6638]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:27 rivoplus sudo[6638]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:27 rivoplus volumio[3108]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 17:03:27 rivoplus volumio[3108]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 30 17:03:27 rivoplus volumio[3108]: info: Received Get System Info Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:03:27 rivoplus volumio[3108]: info: Discovery: Getting this device information Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:27 rivoplus volumio[3108]: info: Listing playlists Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 17:03:27 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 30 17:03:28 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:28.374+09:00 level=WARN msg="received acknowledgment for unexpected write index" component=conn/ble data=0100 addr=1 Dec 30 17:03:28 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:28.441+09:00 level=WARN msg="received acknowledgment for unexpected write index" component=conn/ble data=0100 addr=1 Dec 30 17:03:28 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:28.542+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 17:03:28 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:28.577+09:00 level=WARN msg="received acknowledgment for unexpected write index" component=conn/ble data=0100 addr=1 Dec 30 17:03:29 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 30 17:03:29 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 17:03:29 rivoplus volumio[3108]: info: Received Get System Info Dec 30 17:03:29 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:03:29 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:03:29 rivoplus volumio[3108]: info: Discovery: Getting this device information Dec 30 17:03:29 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:29 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:03:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Dec 30 17:03:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Dec 30 17:03:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Dec 30 17:03:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 17:03:30 rivoplus volumio[3108]: info: Received Get System Info Dec 30 17:03:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:03:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:03:30 rivoplus volumio[3108]: info: Discovery: Getting this device information Dec 30 17:03:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:32 rivoplus volumio[3108]: info: Retrieving Cloud Streaming UI Dec 30 17:03:32 rivoplus volumio[3108]: info: Getting Tidal Cloud Configuration Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:32 rivoplus volumio[3108]: info: Getting Qobuz Cloud Configuration Dec 30 17:03:32 rivoplus volumio[3108]: info: Asking plugin for UI Config Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:32 rivoplus volumio[3108]: info: Getting Spotify Cloud Configuration Dec 30 17:03:32 rivoplus volumio[3108]: info: Asking plugin for UI Config Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:32 rivoplus volumio[3108]: info: Saving Spotify Acccount Dec 30 17:03:32 rivoplus volumio[3108]: info: Got it Dec 30 17:03:32 rivoplus volumio[3108]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 30 17:03:32 rivoplus volumio[3108]: info: Got Tidal Cloud Configuration Dec 30 17:03:32 rivoplus volumio[3108]: info: Got it Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 17:03:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 17:03:35 rivoplus volumio[3108]: info: Disabling MyMusic plugin multiroom Dec 30 17:03:35 rivoplus volumio[3108]: info: Disabling plugin multiroom Dec 30 17:03:35 rivoplus volumio[3108]: info: Preparing to generate the ALSA configuration file Dec 30 17:03:35 rivoplus volumio[3108]: info: Asound.conf file written Dec 30 17:03:35 rivoplus sudo[6644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 17:03:35 rivoplus sudo[6644]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus sudo[6644]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:35 rivoplus volumio[3108]: No state is present for card AMLAUGESOUNDMP1 Dec 30 17:03:35 rivoplus volumio[3108]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Dec 30 17:03:35 rivoplus volumio[3108]: Hardware is initialized using a generic method Dec 30 17:03:35 rivoplus volumio[3108]: No state is present for card AMLAUGESOUNDMP1 Dec 30 17:03:35 rivoplus volumio[3108]: No state is present for card Device Dec 30 17:03:35 rivoplus volumio[3108]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Dec 30 17:03:35 rivoplus volumio[3108]: Hardware is initialized using a generic method Dec 30 17:03:35 rivoplus volumio[3108]: No state is present for card Device Dec 30 17:03:35 rivoplus volumio[3108]: No state is present for card AUDIO Dec 30 17:03:35 rivoplus volumio[3108]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" Dec 30 17:03:35 rivoplus volumio[3108]: Hardware is initialized using a generic method Dec 30 17:03:35 rivoplus volumio[3108]: No state is present for card AUDIO Dec 30 17:03:35 rivoplus volumio[3108]: info: Output device has changed, restarting MPD Dec 30 17:03:35 rivoplus sudo[6649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 17:03:35 rivoplus sudo[6649]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus sudo[6649]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus volumio[3108]: info: Output device has changed, restarting Shairport Sync Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:03:35 rivoplus sudo[6652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 17:03:35 rivoplus sudo[6652]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 30 17:03:35 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:35 rivoplus volumio[3108]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 17:03:35 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:35 rivoplus volumio[3108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 17:03:35 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 17:03:35 rivoplus volumio[3108]: info: QobuzConnect: setDeactiveState invoked Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:35 rivoplus volumio[3108]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus vtcs[6560]: [2025-12-30 17:03:35.445] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 30 17:03:35 rivoplus vtcs[6560]: [2025-12-30 17:03:35.446] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 30 17:03:35 rivoplus vtcs[6560]: [2025-12-30 17:03:35.446] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 30 17:03:35 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 17:03:35 rivoplus volumio[3108]: info: Done. Dec 30 17:03:35 rivoplus sudo[6666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 17:03:35 rivoplus sudo[6666]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 30 17:03:35 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:35 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 30 17:03:35 rivoplus sudo[6669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 17:03:35 rivoplus sudo[6669]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 30 17:03:35 rivoplus sudo[6666]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus sudo[6669]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:35 rivoplus sudo[6677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 17:03:35 rivoplus sudo[6659]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 17:03:35 rivoplus sudo[6659]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus volumio[3108]: info: MPD Permissions set Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus sudo[6677]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus sudo[6659]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus sudo[6677]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus sudo[6682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 17:03:35 rivoplus sudo[6682]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 17:03:35 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 30 17:03:35 rivoplus qobuz-connect[6473]: 20251230 17:03:35.713 [6473.6473] INFO SampleApp: Stopping Local configuration server Dec 30 17:03:35 rivoplus qobuz-connect[6473]: 20251230 17:03:35.723 [6473.6473] INFO SampleApp: shat down connection on UNIX socket Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:35 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 30 17:03:35 rivoplus volumio[3108]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 30 17:03:35 rivoplus volumio[3108]: info: QobuzConnect: setDeactiveState invoked Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:35 rivoplus volumio[3108]: info: Starting Shairport Sync Dec 30 17:03:35 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 30 17:03:35 rivoplus sudo[6682]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:35 rivoplus sudo[6694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 17:03:35 rivoplus sudo[6694]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:35 rivoplus volumio[3108]: info: Executing endpoint qc_getconfig Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 30 17:03:35 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 30 17:03:35 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 30 17:03:35 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 30 17:03:35 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 30 17:03:35 rivoplus sudo[6694]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.901 [6690.6690] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.908 [6690.6690] INFO VolumeManager: [0xab593240]: Setting new playback volume: 75 Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.909 [6690.6690] INFO VolumeManager: [0xab593240]: Setting new mute state: 0 Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.909 [6690.6690] INFO QobuzConnect: [0xab593c10]: Client initialized! Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.909 [6690.6690] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:35 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:35 rivoplus volumio[3108]: info: Shairport-Sync Started Dec 30 17:03:35 rivoplus volumio[3108]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.936 [6690.6690] INFO LocalConfigManager: [0xab592b20]: Starting Local Configuration server Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.936 [6690.6690] INFO SampleApp: Starting Local configuration server Dec 30 17:03:35 rivoplus qobuz-connect[6690]: 20251230 17:03:35.937 [6690.6690] INFO SampleApp: Connected to UNIX socket client 0xab588ed8 Dec 30 17:03:35 rivoplus volumio[3108]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:03:35 rivoplus volumio[3108]: info: Not Reporting Auto name since its the default one Dec 30 17:03:35 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 30 17:03:36 rivoplus qobuz-connect[6690]: 20251230 17:03:36.052 [6690.6690] INFO SampleApp: Playback volume changed: 75 Dec 30 17:03:36 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:03:36 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:36 rivoplus sudo[6712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 30 17:03:36 rivoplus sudo[6712]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:36 rivoplus volumio[3108]: info: Disabling MyMusic plugin upnp Dec 30 17:03:36 rivoplus sudo[6712]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:36 rivoplus sudo[6715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 30 17:03:36 rivoplus sudo[6715]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:36 rivoplus sudo[6716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 30 17:03:36 rivoplus sudo[6716]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:36 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 30 17:03:36 rivoplus volumio[3108]: error: Upnp client error: Error: This socket has been ended by the other party Dec 30 17:03:36 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 30 17:03:36 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:36 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 30 17:03:36 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 30 17:03:36 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:36 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:36 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:36 rivoplus systemd[1]: Started RAAT DAEMON. Dec 30 17:03:36 rivoplus sudo[6715]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:36 rivoplus volumio[3108]: info: Raat Daemon started successfully Dec 30 17:03:36 rivoplus volumio[3108]: info: Executing endpoint restartRAATSocket Dec 30 17:03:36 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 30 17:03:36 rivoplus volumio[3108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 30 17:03:36 rivoplus volumio[3108]: info: Disabling MyMusic plugin metavolumio Dec 30 17:03:36 rivoplus volumio[3108]: info: Removing METAVOLUMIO REST API Endpoints Dec 30 17:03:36 rivoplus volumio[3108]: info: Removing metavolumio REST Endpoint Dec 30 17:03:36 rivoplus volumio[3108]: info: Removing getSimilarArtists REST Endpoint Dec 30 17:03:36 rivoplus volumio[3108]: info: Removing getSimilarAlbums REST Endpoint Dec 30 17:03:36 rivoplus volumio[3108]: info: Removing getSimilarTracks REST Endpoint Dec 30 17:03:36 rivoplus volumio[3108]: info: Disabling plugin metavolumio Dec 30 17:03:36 rivoplus volumio[3108]: info: Done. Dec 30 17:03:36 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 17:03:36 rivoplus volumio[3108]: error: MPD error: Error: This socket has been ended by the other party Dec 30 17:03:36 rivoplus volumio[3108]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 30 17:03:36 rivoplus volumio[3108]: error: MPD error: Error: This socket has been ended by the other party Dec 30 17:03:36 rivoplus volumio[3108]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 30 17:03:36 rivoplus volumio[3108]: error: MPD error: Error: This socket has been ended by the other party Dec 30 17:03:36 rivoplus volumio[3108]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 30 17:03:37 rivoplus mpd[6683]: Dec 30 17:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 17:03:37 rivoplus systemd[1]: Started Music Player Daemon. Dec 30 17:03:37 rivoplus sudo[6652]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:37 rivoplus volumio[3108]: error: updateQueue error: null Dec 30 17:03:37 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 30 17:03:37 rivoplus volumio[3108]: info: Disabling MyMusic plugin upnp Dec 30 17:03:37 rivoplus sudo[6789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 30 17:03:37 rivoplus sudo[6789]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:37 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:38 rivoplus volumio[3108]: info: TidalConnect service stoped! Dec 30 17:03:38 rivoplus volumio[3108]: info: TidalConnect service stoped! Dec 30 17:03:38 rivoplus volumio[3108]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 17:03:38 rivoplus volumio[3108]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 17:03:38 rivoplus sudo[6800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 17:03:38 rivoplus sudo[6800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:38 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 30 17:03:38 rivoplus sudo[6800]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:39 rivoplus volumio[3108]: info: Executing endpoint tc_getconfig Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 30 17:03:39 rivoplus volumio[3108]: info: RAAT: Requesting Headphone Status Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 30 17:03:39 rivoplus vtcs[6802]: STARTING TidalConnect services, version: 1.5.2.56 Dec 30 17:03:39 rivoplus vtcs[6802]: STARTED TidalConnect services. Dec 30 17:03:39 rivoplus volumio[3108]: info: Executing endpoint tc_connect Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 30 17:03:39 rivoplus volumio[3108]: info: Connecting to TidalConnect Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:39 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Dec 30 17:03:39 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 30 17:03:39 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService stop Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:39 rivoplus volumio[3108]: info: No code Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:39 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Dec 30 17:03:39 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 30 17:03:39 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService stop Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:39 rivoplus volumio[3108]: info: No code Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreStateMachine::pushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output update for this device Dec 30 17:03:39 rivoplus volumio[3108]: info: MRS: Pushing multiroomSync output Dec 30 17:03:39 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:39 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:39 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:39 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:39 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:39 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Dec 30 17:03:39 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:39 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:39 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:39 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:39 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:39 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:39 rivoplus volumio[3108]: info: MCU Signalled Headphone Mode Disabled Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 17:03:39 rivoplus volumio[3108]: info: Reporting Headphone State: false Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:03:39 rivoplus volumio[3108]: info: Updating RAAT Signal Path Dec 30 17:03:39 rivoplus volumio[3108]: info: Disabling MyMusic plugin airplay_emulation Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Dec 30 17:03:39 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:03:39 rivoplus volumio[3108]: info: Disabling plugin airplay_emulation Dec 30 17:03:39 rivoplus volumio[3108]: info: Done. Dec 30 17:03:39 rivoplus sudo[6822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Dec 30 17:03:39 rivoplus sudo[6822]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:39 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 30 17:03:39 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 30 17:03:39 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 30 17:03:39 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:39 rivoplus sudo[6822]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:39 rivoplus volumio[3108]: info: Shairport-Sync Stopped Dec 30 17:03:40 rivoplus sudo[6825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 17:03:40 rivoplus sudo[6825]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:40 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 17:03:40 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 30 17:03:40 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:40 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 30 17:03:40 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:40 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 30 17:03:40 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:03:40 rivoplus systemd[1]: Started MPD Monitor Service. Dec 30 17:03:40 rivoplus mpd_monitor.sh[6827]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 17:03:40 rivoplus sudo[6825]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:40 rivoplus volumio[3108]: info: Successfully started MPD Monitor Dec 30 17:03:41 rivoplus volumio[3108]: info: Disabling MyMusic plugin cd_controller Dec 30 17:03:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Dec 30 17:03:41 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:03:41 rivoplus volumio[3108]: info: Stopping UDEV Watcher for CD Dec 30 17:03:41 rivoplus volumio[3108]: info: Disabling plugin cd_controller Dec 30 17:03:41 rivoplus volumio[3108]: info: Done. Dec 30 17:03:41 rivoplus volumio[3108]: info: TidalConnect service started! Dec 30 17:03:42 rivoplus volumio[3108]: info: Disabling MyMusic plugin qobuzconnect Dec 30 17:03:42 rivoplus qobuz-connect[6690]: 20251230 17:03:42.061 [6690.6690] INFO MediaEngine: [0xab592dd8]: Stopping playback, keeping tracks Dec 30 17:03:42 rivoplus volumio[3108]: info: QobuzConnect: setDeactiveState invoked Dec 30 17:03:42 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:42 rivoplus sudo[6832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop qobuz-connect Dec 30 17:03:42 rivoplus sudo[6832]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:42 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 30 17:03:42 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:42 rivoplus qobuz-connect[6690]: 20251230 17:03:42.145 [6690.6690] INFO SampleApp: Stopping Local configuration server Dec 30 17:03:42 rivoplus qobuz-connect[6690]: 20251230 17:03:42.156 [6690.6690] INFO SampleApp: shat down connection on UNIX socket Dec 30 17:03:42 rivoplus volumio[3108]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 30 17:03:42 rivoplus volumio[3108]: info: QobuzConnect: setDeactiveState invoked Dec 30 17:03:42 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 30 17:03:42 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 30 17:03:42 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:42 rivoplus sudo[6832]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:42 rivoplus volumio[3108]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket disconnected and tore down Dec 30 17:03:42 rivoplus volumio[3108]: info: Disabling plugin qobuzconnect Dec 30 17:03:42 rivoplus volumio[3108]: info: Done. Dec 30 17:03:42 rivoplus volumio[3108]: info: Disabling MyMusic plugin raat Dec 30 17:03:42 rivoplus volumio[3108]: info: Stopping RAAT Plugin Dec 30 17:03:42 rivoplus sudo[6835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Dec 30 17:03:42 rivoplus sudo[6835]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:42 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 30 17:03:42 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:42 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 30 17:03:42 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 30 17:03:42 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:42 rivoplus sudo[6835]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:42 rivoplus volumio[3108]: info: Raat Daemon stopped successfully Dec 30 17:03:42 rivoplus volumio[3108]: info: Disabling plugin raat Dec 30 17:03:42 rivoplus volumio[3108]: info: Done. Dec 30 17:03:44 rivoplus volumio[3108]: info: Disabling MyMusic plugin smart_inputs Dec 30 17:03:44 rivoplus volumio[3108]: info: Removeing all smart Inputs Dec 30 17:03:44 rivoplus volumio[3108]: info: Disabling plugin smart_inputs Dec 30 17:03:44 rivoplus volumio[3108]: info: Done. Dec 30 17:03:44 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 17:03:45 rivoplus volumio[3108]: info: Disabling MyMusic plugin tidalconnect Dec 30 17:03:45 rivoplus vtcs[6802]: [2025-12-30 17:03:45.541] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 30 17:03:45 rivoplus vtcs[6802]: [2025-12-30 17:03:45.542] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 30 17:03:45 rivoplus vtcs[6802]: [2025-12-30 17:03:45.542] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 30 17:03:45 rivoplus volumio[3108]: info: Disabling plugin tidalconnect Dec 30 17:03:45 rivoplus volumio[3108]: info: Done. Dec 30 17:03:45 rivoplus sudo[6838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 17:03:45 rivoplus sudo[6838]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:45 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 30 17:03:45 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 30 17:03:45 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 30 17:03:45 rivoplus volumio[3108]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:03:45 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 30 17:03:45 rivoplus sudo[6838]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:45 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 17:03:45 rivoplus volumio[3108]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 30 17:03:45 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 30 17:03:45 rivoplus volumio[3108]: info: Received Get System Version Dec 30 17:03:45 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 17:03:45 rivoplus volumio[3108]: info: Received Get System Info Dec 30 17:03:45 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:03:45 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:03:45 rivoplus volumio[3108]: info: Discovery: Getting this device information Dec 30 17:03:45 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Dec 30 17:03:45 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:03:46 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:46.016+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 17:03:46 rivoplus volumio[3108]: info: Disabling MyMusic plugin upnp_browser Dec 30 17:03:46 rivoplus volumio[3108]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesDLNAサーバー Dec 30 17:03:46 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:03:46 rivoplus volumio[3108]: info: Disabling plugin upnp_browser Dec 30 17:03:46 rivoplus volumio[3108]: info: Done. Dec 30 17:03:46 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:46.195+09:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=0100 addr=1 Dec 30 17:03:48 rivoplus volumio[3108]: info: TidalConnect service stoped! Dec 30 17:03:48 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 17:03:49 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:49.604+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 17:03:50 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:03:50.043+09:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=0100 addr=1 Dec 30 17:03:52 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 17:03:53 rivoplus go-librespot[4486]: time="2025-12-30T17:03:53+09:00" level=trace msg="sent dealer ping" Dec 30 17:03:53 rivoplus go-librespot[4486]: time="2025-12-30T17:03:53+09:00" level=trace msg="received dealer pong" Dec 30 17:03:57 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:03:57 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 17:03:57 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 17:03:57 rivoplus sudo[6879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 17:03:57 rivoplus sudo[6879]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:57 rivoplus sudo[6879]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:57 rivoplus sudo[6884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 17:03:57 rivoplus sudo[6884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:57 rivoplus sudo[6884]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: Error getting the SSID Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus sudo[6893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 17:03:57 rivoplus sudo[6893]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: Error getting the SSID Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus sudo[6893]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:57 rivoplus sudo[6901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 17:03:57 rivoplus sudo[6901]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: Error getting the SSID Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:03:57 rivoplus sudo[6901]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:57 rivoplus sudo[6911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 17:03:57 rivoplus sudo[6911]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:57 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 17:03:57 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 17:03:57 rivoplus sudo[6911]: pam_unix(sudo:session): session closed for user root Dec 30 17:03:57 rivoplus sudo[6915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 17:03:57 rivoplus sudo[6915]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:03:57 rivoplus sudo[6915]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:04:02 rivoplus volumio[3108]: info: Retrieving Cloud Streaming UI Dec 30 17:04:02 rivoplus volumio[3108]: info: Getting Tidal Cloud Configuration Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:04:02 rivoplus volumio[3108]: info: Getting Qobuz Cloud Configuration Dec 30 17:04:02 rivoplus volumio[3108]: info: Asking plugin for UI Config Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:04:02 rivoplus volumio[3108]: info: Getting Spotify Cloud Configuration Dec 30 17:04:02 rivoplus volumio[3108]: info: Asking plugin for UI Config Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:04:02 rivoplus volumio[3108]: info: Saving Spotify Acccount Dec 30 17:04:02 rivoplus volumio[3108]: info: Got it Dec 30 17:04:02 rivoplus volumio[3108]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 30 17:04:02 rivoplus volumio[3108]: info: Got Tidal Cloud Configuration Dec 30 17:04:02 rivoplus volumio[3108]: info: Got it Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 17:04:02 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 17:04:05 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:04:05 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 17:04:05 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 17:04:05 rivoplus sudo[6924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 17:04:05 rivoplus sudo[6924]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:05 rivoplus sudo[6924]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:05 rivoplus sudo[6930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 17:04:05 rivoplus sudo[6930]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:05 rivoplus sudo[6930]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: Error getting the SSID Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus sudo[6937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 17:04:05 rivoplus sudo[6937]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: Error getting the SSID Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus sudo[6937]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:05 rivoplus sudo[6944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 17:04:05 rivoplus sudo[6944]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: Error getting the SSID Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 17:04:05 rivoplus sudo[6944]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:05 rivoplus sudo[6952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 17:04:05 rivoplus sudo[6952]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:05 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 17:04:05 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 17:04:05 rivoplus sudo[6952]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:05 rivoplus sudo[6955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 17:04:05 rivoplus sudo[6955]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:05 rivoplus sudo[6955]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 17:04:09 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 17:04:09 rivoplus volumio[3108]: xcb_connection_has_error() returned true Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 30 17:04:09 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 30 17:04:09 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:04:09.711+09:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Dec 30 17:04:09 rivoplus go-librespot[4486]: time="2025-12-30T17:04:09+09:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 30 17:04:09 rivoplus sudo[6716]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:09 rivoplus systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Dec 30 17:04:09 rivoplus volumio-remote-updater[2827]: [2025-12-30 17:04:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 30 17:04:09 rivoplus volumio-remote-updater[2827]: [2025-12-30 17:04:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 30 17:04:09 rivoplus sudo[6789]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:09 rivoplus systemd[1]: upmpdcli.service: Succeeded. Dec 30 17:04:09 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:04:09.731+09:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Dec 30 17:04:09 rivoplus systemd[1]: Stopped UPnP Renderer front-end to MPD. Dec 30 17:04:09 rivoplus systemd[1]: volumio.service: Failed with result 'signal'. Dec 30 17:04:09 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:04:09.784+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 17:04:09 rivoplus systemd[1]: Started dynamicswap service. Dec 30 17:04:09 rivoplus systemd[1]: dynamicswap.service: Succeeded. Dec 30 17:04:09 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:04:09.955+09:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=0100 addr=1 Dec 30 17:04:10 rivoplus systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 30 17:04:10 rivoplus systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 30 17:04:10 rivoplus systemd[1]: Stopped Volumio Backend Module. Dec 30 17:04:10 rivoplus systemd[1]: Started Volumio Backend Module. Dec 30 17:04:10 rivoplus systemd[1]: Started dynamicswap service. Dec 30 17:04:10 rivoplus systemd[1]: dynamicswap.service: Succeeded. Dec 30 17:04:10 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:04:10.735+09:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Dec 30 17:04:12 rivoplus volumio[6997]: info: ------------------------------------------- Dec 30 17:04:12 rivoplus volumio[6997]: info: ----- Volumio3 ---- Dec 30 17:04:12 rivoplus volumio[6997]: info: ------------------------------------------- Dec 30 17:04:12 rivoplus volumio[6997]: info: ----- System startup ---- Dec 30 17:04:12 rivoplus volumio[6997]: info: ------------------------------------------- Dec 30 17:04:12 rivoplus volumio[6997]: info: MYVOLUMIO Environment detected Dec 30 17:04:13 rivoplus volumio[6997]: info: Plugin folders cleanup Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning into folder /volumio/app/plugins/ Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning category audio_interface Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning category miscellanea Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning category music_service Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning category plugins.json Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning category system_controller Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning category user_interface Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning into folder /data/plugins/ Dec 30 17:04:13 rivoplus volumio[6997]: info: Scanning category music_service Dec 30 17:04:13 rivoplus volumio[6997]: info: Plugin folders cleanup completed Dec 30 17:04:13 rivoplus volumio[6997]: info: ------------------------------------------- Dec 30 17:04:13 rivoplus volumio[6997]: info: ----- Core plugins startup ---- Dec 30 17:04:13 rivoplus volumio[6997]: info: ------------------------------------------- Dec 30 17:04:13 rivoplus volumio[6997]: info: Loading plugins from folder /volumio/app/plugins/ Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin bluetooth to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin multiroom to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin upnp to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin metavolumio to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin cd_controller to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin raat to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 30 17:04:13 rivoplus volumio[6997]: info: Loading plugins from folder /data/plugins/ Dec 30 17:04:13 rivoplus volumio[6997]: info: Loading plugin "system"... Dec 30 17:04:13 rivoplus volumio[6997]: info: Loading plugin "appearance"... Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "network"... Dec 30 17:04:14 rivoplus volumio[6997]: info: Refreshing Cached IP Addresses Dec 30 17:04:14 rivoplus sudo[7025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 17:04:14 rivoplus sudo[7025]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "services"... Dec 30 17:04:14 rivoplus sudo[7027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 17:04:14 rivoplus sudo[7027]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "volumio5onboarding"... Dec 30 17:04:14 rivoplus sudo[7025]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:14 rivoplus sudo[7027]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "alsa_controller"... Dec 30 17:04:14 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "wizard"... Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "networkfs"... Dec 30 17:04:14 rivoplus volumio[6997]: info: Starting Udev Watcher for removable devices Dec 30 17:04:14 rivoplus volumio[6997]: info: Ignoring mount for partition: BOOT Dec 30 17:04:14 rivoplus volumio[6997]: info: Ignoring mount for partition: volumio Dec 30 17:04:14 rivoplus volumio[6997]: info: Ignoring mount for partition: volumio_data Dec 30 17:04:14 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "volumio_command_line_client"... Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "upnp"... Dec 30 17:04:14 rivoplus volumio[6997]: info: [1767081854536] Starting Upmpd Daemon Dec 30 17:04:14 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "my_music"... Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "mpd"... Dec 30 17:04:14 rivoplus volumio-remote-updater[2827]: [2025-12-30 17:04:14] [connect] Successful connection Dec 30 17:04:14 rivoplus volumio[6997]: info: Plugin upnp_browser is not enabled Dec 30 17:04:14 rivoplus volumio[6997]: info: Loading plugin "bluetooth"... Dec 30 17:04:15 rivoplus volumio[6997]: info: [1767081855155] Starting BluetoothController Dec 30 17:04:15 rivoplus volumio[6997]: info: Loading plugin "alarm-clock"... Dec 30 17:04:15 rivoplus volumio[6997]: info: Loading plugin "manifestui"... Dec 30 17:04:15 rivoplus volumio[6997]: info: Plugin metavolumio is not enabled Dec 30 17:04:15 rivoplus volumio[6997]: info: Plugin airplay_emulation is not enabled Dec 30 17:04:15 rivoplus volumio[6997]: info: Plugin cd_controller is not enabled Dec 30 17:04:15 rivoplus volumio[6997]: info: Loading plugin "last_100"... Dec 30 17:04:15 rivoplus volumio[6997]: info: Plugin qobuzconnect is not enabled Dec 30 17:04:15 rivoplus volumio[6997]: info: Plugin raat is not enabled Dec 30 17:04:15 rivoplus volumio[6997]: info: Loading plugin "streaming_services"... Dec 30 17:04:16 rivoplus volumio[6997]: info: Starting Streaming Service Transparent Proxy Dec 30 17:04:16 rivoplus volumio[6997]: info: Plugin tidalconnect is not enabled Dec 30 17:04:16 rivoplus volumio[6997]: info: Loading plugin "webradio"... Dec 30 17:04:16 rivoplus volumio[6997]: info: Loading plugin "i2s_dacs"... Dec 30 17:04:16 rivoplus volumio[6997]: info: I2S DAC not set, start Auto-detection Dec 30 17:04:16 rivoplus volumio[6997]: info: Loading plugin "volumiodiscovery"... Dec 30 17:04:16 rivoplus volumio[6997]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 17:04:16 rivoplus volumio[6997]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 17:04:16 rivoplus node[6997]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 17:04:16 rivoplus volumio[6997]: *** WARNING *** For more information see Dec 30 17:04:16 rivoplus volumio[6997]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 17:04:16 rivoplus volumio[6997]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 17:04:16 rivoplus volumio[6997]: *** WARNING *** For more information see Dec 30 17:04:16 rivoplus node[6997]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 17:04:16 rivoplus node[6997]: *** WARNING *** For more information see Dec 30 17:04:16 rivoplus node[6997]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 17:04:16 rivoplus node[6997]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 17:04:16 rivoplus node[6997]: *** WARNING *** For more information see Dec 30 17:04:16 rivoplus volumio[6997]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 30 17:04:16 rivoplus volumio[6997]: info: Discovery: Started advertising with name: Rivoplus Dec 30 17:04:17 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 17:04:17 rivoplus volumio[6997]: info: Loading plugin "spop"... Dec 30 17:04:17 rivoplus go-librespot[4486]: time="2025-12-30T17:04:17+09:00" level=debug msg="handling update_context player command from ae9fa72e7bdf601bfefe1272a12caf7caaed7321" Dec 30 17:04:17 rivoplus go-librespot[4486]: time="2025-12-30T17:04:17+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 30 17:04:17 rivoplus go-librespot[4486]: time="2025-12-30T17:04:17+09:00" level=debug msg="sending successful reply for dealer request" Dec 30 17:04:18 rivoplus volumio[6997]: STREAMING PROXY: Starting server on port 3245 Dec 30 17:04:18 rivoplus volumio[6997]: Node JS runtime: 14 Dec 30 17:04:18 rivoplus volumio[6997]: info: Plugin multiroom is not enabled Dec 30 17:04:18 rivoplus volumio[6997]: info: Loading plugin "outputs"... Dec 30 17:04:18 rivoplus volumio[6997]: info: Loading plugin "albumart"... Dec 30 17:04:18 rivoplus volumio[6997]: info: Plugin example_plugin is not enabled Dec 30 17:04:18 rivoplus volumio[6997]: info: Loading plugin "hi_res_audio"... Dec 30 17:04:18 rivoplus volumio[6997]: Forking 3 albumart workers Dec 30 17:04:18 rivoplus go-librespot[4486]: time="2025-12-30T17:04:18+09:00" level=info msg="playback was transferred to iPhone" Dec 30 17:04:18 rivoplus go-librespot[4486]: time="2025-12-30T17:04:18+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 30 17:04:18 rivoplus go-librespot[4486]: time="2025-12-30T17:04:18+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 30 17:04:18 rivoplus go-librespot[4486]: time="2025-12-30T17:04:18+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 370" Dec 30 17:04:18 rivoplus go-librespot[4486]: time="2025-12-30T17:04:18+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1093" Dec 30 17:04:19 rivoplus volumio[6997]: info: Applying required configuration parameters for plugin hi_res_audio Dec 30 17:04:19 rivoplus volumio[6997]: info: Loading plugin "inputs"... Dec 30 17:04:19 rivoplus volumio[6997]: Starting albumart workers Dec 30 17:04:19 rivoplus volumio[6997]: Starting albumart workers Dec 30 17:04:19 rivoplus volumio[6997]: Starting albumart workers Dec 30 17:04:20 rivoplus volumio[6997]: info: Loading plugin "qobuz"... Dec 30 17:04:21 rivoplus volumio[6997]: info: Plugin smart_inputs is not enabled Dec 30 17:04:21 rivoplus volumio[6997]: info: Loading plugin "tidal"... Dec 30 17:04:21 rivoplus volumio5-onboarding[4049]: time=2025-12-30T17:04:21.737+09:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:55882->127.0.0.1:3000: i/o timeout" Dec 30 17:04:22 rivoplus volumio[6997]: info: Loading plugin "rivopluscontrol"... Dec 30 17:04:22 rivoplus volumio[6997]: info: Adding this device properties Dec 30 17:04:22 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Dec 30 17:04:22 rivoplus volumio[6997]: info: Setting Additional Device Volumio Properties: [object Object] Dec 30 17:04:22 rivoplus volumio[6997]: info: Loading plugin "updater_comm"... Dec 30 17:04:23 rivoplus volumio[6997]: info: Loading plugin "cec_controller"... Dec 30 17:04:23 rivoplus go-librespot[4486]: time="2025-12-30T17:04:23+09:00" level=trace msg="sent dealer ping" Dec 30 17:04:23 rivoplus volumio[6997]: info: Applying required configuration parameters for plugin cec_controller Dec 30 17:04:23 rivoplus volumio[6997]: info: Plugin mpdemulation is not enabled Dec 30 17:04:23 rivoplus volumio[6997]: info: Loading plugin "rest_api"... Dec 30 17:04:23 rivoplus volumio[6997]: info: Loading plugin "websocket"... Dec 30 17:04:23 rivoplus volumio[6997]: info: Starting Socket.io Server version 2.3.0 Dec 30 17:04:23 rivoplus volumio[6997]: info: Loading i18n strings for locale ja Dec 30 17:04:23 rivoplus volumio[6997]: Updating browse sources language Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::initPlayerControls Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: Express server listening on port 3000 Dec 30 17:04:23 rivoplus volumio[6997]: [Metrics] WebUI: 12s 138.19ms Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreStateMachine::resetVolumioState Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreStateMachine::getcurrentVolume Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:23 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:23 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:04:23 rivoplus volumio[6997]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 30 17:04:23 rivoplus volumio[6997]: info: Completed loading Core Plugins Dec 30 17:04:23 rivoplus volumio[6997]: info: Preparing to generate the ALSA configuration file Dec 30 17:04:23 rivoplus volumio[6997]: info: Cannot read play queue from file Dec 30 17:04:23 rivoplus volumio[6997]: info: Volumio Network Manager: Network status updated: 1 Dec 30 17:04:23 rivoplus volumio[6997]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Dec 30 17:04:23 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Dec 30 17:04:23 rivoplus volumio[6997]: info: Asound.conf file unchanged, so no further update is needed Dec 30 17:04:23 rivoplus volumio[6997]: info: Output device has changed, restarting MPD Dec 30 17:04:24 rivoplus sudo[7129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 17:04:24 rivoplus sudo[7129]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus volumio[6997]: info: ___________ START PLUGINS ___________ Dec 30 17:04:24 rivoplus sudo[7129]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus sudo[7131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 17:04:24 rivoplus sudo[7131]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus volumio[6997]: info: ControllerMpd::onStart: Initializing MPD Dec 30 17:04:24 rivoplus volumio[6997]: info: Creating MPD Configuration file Dec 30 17:04:24 rivoplus sudo[7135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Dec 30 17:04:24 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 30 17:04:24 rivoplus sudo[7135]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7135]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus sudo[7138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 17:04:24 rivoplus sudo[7138]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 17:04:24 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 17:04:24 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 17:04:24 rivoplus sudo[7138]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 17:04:24 rivoplus sudo[7140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 17:04:24 rivoplus volumio[6997]: info: [1767081864165] CoreMusicLibrary::Adding element Last_100 Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:04:24 rivoplus sudo[7140]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus volumio[6997]: info: Streaming services startup Dec 30 17:04:24 rivoplus volumio[6997]: info: Starting Streaming Daemon Dec 30 17:04:24 rivoplus sudo[7150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivoplus Dec 30 17:04:24 rivoplus sudo[7150]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7150]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 30 17:04:24 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 17:04:24 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 17:04:24 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 17:04:24 rivoplus sudo[7153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 17:04:24 rivoplus sudo[7153]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7153]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 17:04:24 rivoplus volumio[6997]: info: [1767081864290] CoreMusicLibrary::Adding element Webradio Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 17:04:24 rivoplus volumio[6997]: info: Initializing BBC Radios Dec 30 17:04:24 rivoplus sudo[7156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 17:04:24 rivoplus sudo[7156]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:04:24 rivoplus sudo[7156]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus volumio[6997]: info: Creating Spotify config file Dec 30 17:04:24 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:24 rivoplus volumio[6997]: error: Hi Res Audio Failed Login: Missing Login Data Dec 30 17:04:24 rivoplus volumio[6997]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 30 17:04:24 rivoplus volumio[6997]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 17:04:24 rivoplus volumio[6997]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 17:04:24 rivoplus volumio[6997]: info: Initializing Serial Communication on port /dev/ttyS3 Dec 30 17:04:24 rivoplus volumio[6997]: info: Touch Event Listener Process Starting Dec 30 17:04:24 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Dec 30 17:04:24 rivoplus sudo[7195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Dec 30 17:04:24 rivoplus sudo[7195]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7195]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus sudo[7200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Dec 30 17:04:24 rivoplus sudo[7200]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7200]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus sudo[7203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Dec 30 17:04:24 rivoplus sudo[7203]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7203]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus sudo[7206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Dec 30 17:04:24 rivoplus sudo[7206]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7206]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus sudo[7209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Dec 30 17:04:24 rivoplus sudo[7209]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7209]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus volumio[6997]: info: Apply VIM3L Onboard LEDs Settings Dec 30 17:04:24 rivoplus volumio[6997]: info: Turning On Onboard LEDs Dec 30 17:04:24 rivoplus sudo[7213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 30 17:04:24 rivoplus sudo[7213]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus volumio[6997]: info: Starting CEC Event listener Dec 30 17:04:24 rivoplus sudo[7213]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus volumio[6997]: info: Volumio Calling Home Dec 30 17:04:24 rivoplus sudo[7217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 30 17:04:24 rivoplus sudo[7217]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:24 rivoplus sudo[7217]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:24 rivoplus volumio[6997]: info: Stopping AccessToken refresher cron for QOBUZ Dec 30 17:04:24 rivoplus volumio[6997]: info: AccessToken refresher cron started for QOBUZ Dec 30 17:04:24 rivoplus volumio[6997]: info: Adding TIDAL REST API Endpoints Dec 30 17:04:24 rivoplus volumio[6997]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 30 17:04:24 rivoplus volumio[6997]: info: Adding QOBUZ REST API Endpoints Dec 30 17:04:24 rivoplus volumio[6997]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreStateMachine::setRepeat null single undefined Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:25 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreStateMachine::setRandom null Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:25 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:25 rivoplus sudo[7225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 17:04:25 rivoplus sudo[7225]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:25 rivoplus volumio[6997]: info: Serial port opened successfully Dec 30 17:04:25 rivoplus volumio[6997]: info: Sending serial start messages Dec 30 17:04:25 rivoplus sudo[7225]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:25 rivoplus sudo[7229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 17:04:25 rivoplus sudo[7229]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: Reporting MCU Network Status: 1 Dec 30 17:04:25 rivoplus sudo[7229]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:25 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Dec 30 17:04:25 rivoplus volumio[6997]: error: Failed to initialize COMTRUE with silence: Error: Command failed: /usr/bin/aplay -D plughw:Device /volumio/app/silence.wav Dec 30 17:04:25 rivoplus volumio[6997]: aplay: main:828: audio open error: Device or resource busy Dec 30 17:04:25 rivoplus volumio[6997]: info: Touch Event Listener Process Closed Dec 30 17:04:25 rivoplus volumio[6997]: error: Cannot start Volumio Streaming Daemon Dec 30 17:04:25 rivoplus volumio[6997]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 17:04:25 rivoplus volumio[6997]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 17:04:25 rivoplus volumio[6997]: info: Bluetooth name changed to Rivoplus Dec 30 17:04:25 rivoplus volumio[6997]: info: MPD Permissions set Dec 30 17:04:25 rivoplus volumio[6997]: info: MPD Permissions set Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Dec 30 17:04:25 rivoplus volumio[6997]: info: Setting Device Volume Override Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 17:04:25 rivoplus volumio[6997]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 17:04:25 rivoplus volumio[6997]: info: Disabling external Volume Control Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:25 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:25 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:25 rivoplus volumio[6997]: info: MP1 GPIO: Signalled system ready via GPIO Dec 30 17:04:25 rivoplus volumio[6997]: info: Spotify config file written Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 17:04:25 rivoplus volumio[6997]: info: Setting Additional System Software info: Hardware Revision: 1.0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 17:04:25 rivoplus volumio[6997]: info: Setting HW Firmware info: undefined Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 17:04:25 rivoplus volumio[6997]: info: Setting HW Version info: 1.0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 17:04:25 rivoplus volumio[6997]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 17:04:25 rivoplus volumio[6997]: info: Setting HW Firmware info: 0.3.0 Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 17:04:25 rivoplus volumio[6997]: info: Setting HW Version info: 1.0 Dec 30 17:04:25 rivoplus volumio[6997]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 17:04:25 rivoplus volumio[6997]: info: MCU Signalled Headphone Mode Disabled Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 17:04:25 rivoplus volumio[6997]: info: MCU Signalled Sleep Mode Disabled Dec 30 17:04:25 rivoplus sudo[7235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 30 17:04:25 rivoplus sudo[7235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:25 rivoplus volumio[6997]: info: Enabling Advanced system settings configuration Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Dec 30 17:04:25 rivoplus volumio[6997]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 17:04:25 rivoplus volumio[6997]: info: MCU Signalled Auto Boot Mode On Power Disabled Dec 30 17:04:25 rivoplus systemd[1]: Stopping go-librespot Daemon... Dec 30 17:04:25 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 30 17:04:25 rivoplus systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 17:04:25 rivoplus sudo[7241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Dec 30 17:04:25 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 Dec 30 17:04:25 rivoplus sudo[7241]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus sudo[7241]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:25 rivoplus systemd[1]: go-librespot-daemon.service: Succeeded. Dec 30 17:04:25 rivoplus systemd[1]: Stopped go-librespot Daemon. Dec 30 17:04:25 rivoplus systemd[1]: Started go-librespot Daemon. Dec 30 17:04:25 rivoplus sudo[7235]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:25 rivoplus go-librespot[7245]: go-librespot daemon starting... Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=info msg="running go-librespot 0.4.0" Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=debug msg="app state loaded" Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=debug msg="stored credentials not found" Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 17:04:25 rivoplus volumio[6997]: info: No need to fix Spotify hosts Dec 30 17:04:25 rivoplus volumio[6997]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Dec 30 17:04:25 rivoplus volumio[6997]: info: Discovery: adding c12d642e-167c-4ae9-9dd7-7c1aec870545 Dec 30 17:04:25 rivoplus volumio[6997]: info: Discovery: Found device Rivoplus Dec 30 17:04:25 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:25 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:25 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 30 17:04:25 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 30 17:04:25 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 30 17:04:25 rivoplus go-librespot[7245]: time="2025-12-30T17:04:25+09:00" level=info msg="zeroconf server listening on port 35293" Dec 30 17:04:26 rivoplus sudo[7266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 17:04:26 rivoplus sudo[7266]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:26 rivoplus sudo[7266]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:26 rivoplus volumio[6997]: info: Volumio called home Dec 30 17:04:26 rivoplus volumio[6997]: info: Adding Inputs via Serial API Dec 30 17:04:26 rivoplus volumio[6997]: info: Adding Advanced Audio Settings via Serial API Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 30 17:04:26 rivoplus volumio[6997]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 17:04:26 rivoplus sudo[7269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 30 17:04:26 rivoplus sudo[7269]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:26 rivoplus volumio[6997]: info: Received Get System Info Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:04:26 rivoplus volumio[6997]: info: Discovery: Getting this device information Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: Listing playlists Dec 30 17:04:26 rivoplus systemd[1]: Started UPnP Renderer front-end to MPD. Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetQueue Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreStateMachine::getQueue Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getQueue Dec 30 17:04:26 rivoplus sudo[7269]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::servicePushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} Dec 30 17:04:26 rivoplus volumio[6997]: verbose: CURRENT POSITION 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreStateMachine::syncState stateService stop Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreStateMachine::syncState currentStatus stop Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:26 rivoplus volumio[6997]: info: No code Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreStateMachine::pushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioPushState Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumiosetSourceActiveno-source Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:04:26 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:26 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:26 rivoplus volumio[6997]: info: Upmpdcli Daemon Started Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 17:04:26 rivoplus mpd[7186]: Dec 30 17:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 17:04:26 rivoplus systemd[1]: Started Music Player Daemon. Dec 30 17:04:26 rivoplus sudo[7131]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:26 rivoplus sudo[7140]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 17:04:26 rivoplus volumio[6997]: info: MCU Signalled Playback Inactive Dec 30 17:04:26 rivoplus volumio[6997]: info: Completed starting Core Plugins Dec 30 17:04:26 rivoplus volumio[6997]: info: ------------------------------------------- Dec 30 17:04:26 rivoplus volumio[6997]: info: ----- MyVolumio plugins startup ---- Dec 30 17:04:26 rivoplus volumio[6997]: info: ------------------------------------------- Dec 30 17:04:26 rivoplus volumio[6997]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:26 rivoplus volumio[6997]: error: MPD error: The expression evaluated to a falsy value: Dec 30 17:04:26 rivoplus volumio[6997]: assert.ok(self.idling) Dec 30 17:04:26 rivoplus volumio[6997]: error: The expression evaluated to a falsy value: Dec 30 17:04:26 rivoplus volumio[6997]: assert.ok(self.idling) Dec 30 17:04:26 rivoplus volumio[6997]: info: MPD running with PID7186 Dec 30 17:04:26 rivoplus volumio[6997]: ,establishing connection Dec 30 17:04:26 rivoplus volumio[6997]: error: updateQueue error: null Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:26 rivoplus volumio[6997]: error: updateQueue error: null Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:26 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[7271]: writing RSA key Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus sudo[7306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 30 17:04:27 rivoplus sudo[7306]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:27 rivoplus volumio-remote-updater[2827]: [2025-12-30 17:04:27] [connect] Successful connection Dec 30 17:04:27 rivoplus sudo[7306]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: info: Volumio BT Module successfully started Dec 30 17:04:27 rivoplus volumio-remote-updater[2827]: [2025-12-30 17:04:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1767081867 101 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 17:04:27 rivoplus volumio[6997]: info: Turning Off Onboard LEDs Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 17:04:27 rivoplus sudo[7318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 30 17:04:27 rivoplus sudo[7318]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:27 rivoplus sudo[7318]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 30 17:04:27 rivoplus sudo[7323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 30 17:04:27 rivoplus sudo[7323]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus sudo[7323]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:27 rivoplus volumio[6997]: info: Onboard LEDs initialized Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:27 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 17:04:27 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:27 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 17:04:27 rivoplus volumio[6997]: info: Received Get System Info Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 17:04:27 rivoplus volumio[6997]: info: Discovery: Getting this device information Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:27 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 17:04:27 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:27 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:27 rivoplus volumio[6997]: info: Listing playlists Dec 30 17:04:28 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 17:04:28 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 17:04:28 rivoplus volumio[6997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 17:04:28 rivoplus volumio[6997]: info: go-librespot daemon successfully initialized Dec 30 17:04:29 rivoplus sudo[7335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 17:04:29 rivoplus sudo[7335]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:29 rivoplus sudo[7336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 17:04:29 rivoplus sudo[7336]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 17:04:29 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 30 17:04:29 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 30 17:04:29 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 30 17:04:29 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 30 17:04:29 rivoplus volumio[6997]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 17:04:29 rivoplus volumio[6997]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:04:29 rivoplus volumio[6997]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 17:04:29 rivoplus systemd[1]: Started MPD Monitor Service. Dec 30 17:04:29 rivoplus sudo[7335]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:29 rivoplus sudo[7336]: pam_unix(sudo:session): session closed for user root Dec 30 17:04:29 rivoplus mpd_monitor.sh[7339]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 17:04:29 rivoplus volumio[6997]: info: Successfully started MPD Monitor Dec 30 17:04:29 rivoplus volumio[6997]: info: Successfully started MPD Monitor Dec 30 17:04:30 rivoplus go-librespot[7245]: time="2025-12-30T17:04:30+09:00" level=debug msg="obtained new client token: AABolhxzlzVZyJ6S3taoyUK0vYnn9GeGno1wOizRb0aDWEZLgiuezu8AAn2rQs2eAUcTe1h48umD//QAaf4zdmUqfvutMUoaAWTOphXZmKyRiDN3UVaJw/yM4oVX4N0Qzs9s+rkTLyIGRCwjdG1SZXmxM5y7ay/Nxs7Fyy55quKNO3OcXVVGBnZV3Iumo+szA6YASXkg5ChBIeN81Q3Xb/00UjghhVi0PMY+P5amAAaLZ8WAKS13JAuqxA==" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="completed keyexchange" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="completed challenge" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=info msg="authenticated AP" username="mn*********************mt" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=info msg="authenticated Login5" username="mn*********************mt" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=info msg="accepted zeroconf from iPhone" username="mn*********************mt" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="dealer connection opened" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=trace msg="starting accesspoint recv loop" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=trace msg="starting dealer recv loop" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=trace msg="received accesspoint ping" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="received connection id: NGUyMDU3MTctZjVk...OUNFNTdGQ0MyMA==" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=trace msg="received accesspoint pong ack" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="put connect state because NEW_DEVICE" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="update volume requested to 65535/65535" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="handling transfer player command from ae9fa72e7bdf601bfefe1272a12caf7caaed7321" Dec 30 17:04:31 rivoplus volumio[6997]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EVJHK7Q1TBABQ" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EVJHK7Q1TBABQ" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="loading track (paused: true, position: 6271ms)" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=trace msg="emitting websocket event: will_play" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1125" Dec 30 17:04:31 rivoplus volumio[6997]: info: Initializing connection to go-librespot Websocket Dec 30 17:04:31 rivoplus go-librespot[7245]: time="2025-12-30T17:04:31+09:00" level=debug msg="new websocket client" Dec 30 17:04:31 rivoplus volumio[6997]: info: Connection to go-librespot Websocket established Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="selected format OGG_VORBIS_320 (e810f5febcb163084a4261cf7c401d3cd12a0c1a)" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="requested aes key for file e810f5febcb163084a4261cf7c401d3cd12a0c1a, gid: 59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=trace msg="found 3 cdn urls" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="fetched first chunk of 14, total size is 7018547 bytes" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=trace msg="seek to 6271ms (diff: 89ms, samples: 276551, bytes: 216897)" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="created new output device" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=info msg="loaded track \"IRIS OUT\" (paused: true, position: 6271ms, duration: 151573ms, prefetched: false)" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=trace msg="emitting websocket event: metadata" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=trace msg="emitting websocket event: active" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="sending successful reply for dealer request" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 30 17:04:32 rivoplus volumio[6997]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:59hVbgr8rfYkDbHfr8RcGI","name":"IRIS OUT","artist_names":["Kenshi Yonezu"],"album_name":"IRIS OUT","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026d1d4e875e2424cdd07f3232","position":6271,"duration":151573,"release_date":"year:2025 month:9 day:15","track_number":1,"disc_number":1}} Dec 30 17:04:32 rivoplus volumio[6997]: SPOTIFY: received: {"type":"active","data":null} Dec 30 17:04:32 rivoplus volumio[6997]: info: Aligning Spotify Volume to Volumio Volume Dec 30 17:04:32 rivoplus volumio[6997]: info: CoreCommandRouter::volumioGetState Dec 30 17:04:32 rivoplus volumio[6997]: info: CorePlayQueue::getTrack 0 Dec 30 17:04:32 rivoplus volumio[6997]: info: Setting Spotify Volume from Volumio: 100 Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:59hVbgr8rfYkDbHfr8RcGI" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=trace msg="emitting websocket event: paused" Dec 30 17:04:32 rivoplus volumio[6997]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EVJHK7Q1TBABQ","uri":"spotify:track:59hVbgr8rfYkDbHfr8RcGI","play_origin":"playlist/ondemand"}} Dec 30 17:04:32 rivoplus volumio[6997]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 17:04:32 rivoplus volumio[6997]: TypeError: Cannot read property 'service' of undefined Dec 30 17:04:32 rivoplus volumio[6997]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 30 17:04:32 rivoplus volumio[6997]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Dec 30 17:04:32 rivoplus volumio[6997]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 30 17:04:32 rivoplus volumio[6997]: at WebSocket.emit (events.js:400:28) Dec 30 17:04:32 rivoplus volumio[6997]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 30 17:04:32 rivoplus volumio[6997]: at Receiver.emit (events.js:400:28) Dec 30 17:04:32 rivoplus volumio[6997]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 30 17:04:32 rivoplus volumio[6997]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 30 17:04:32 rivoplus volumio[6997]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 30 17:04:32 rivoplus volumio[6997]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 30 17:04:32 rivoplus volumio[6997]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 30 17:04:32 rivoplus go-librespot[7245]: time="2025-12-30T17:04:32+09:00" level=trace msg="emitting websocket event: volume" Dec 30 17:04:33 rivoplus sudo[7353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 17:03 Dec 30 17:04:33 rivoplus sudo[7353]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"