-- Logs begin at Tue 2026-02-24 17:58:56 JST, end at Tue 2026-02-24 18:07:20 JST. -- Feb 24 18:06:01 rivoplus volumio[3179]: info: CoreCommandRouter::volumioVolatilePlay Feb 24 18:06:01 rivoplus volumio[3179]: info: CoreStateMachine::volatilePlay Feb 24 18:06:01 rivoplus volumio[3179]: info: WARNING: No play method for volatile plugin undefined Feb 24 18:06:02 rivoplus volumio[3179]: info: CoreCommandRouter::volumioNext Feb 24 18:06:02 rivoplus volumio[3179]: info: CoreStateMachine::next Feb 24 18:06:02 rivoplus volumio[3179]: info: WARNING: Cannot execute Action because no volatile plugin is defined Feb 24 18:06:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetQueue Feb 24 18:06:03 rivoplus volumio[3179]: info: CoreStateMachine::getQueue Feb 24 18:06:03 rivoplus volumio[3179]: info: CorePlayQueue::getQueue Feb 24 18:06:06 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 18:06:06 rivoplus volumio[3179]: info: CURURI: genres:// Feb 24 18:06:06 rivoplus volumio[3179]: info: Preload queue cleared Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:06:13 rivoplus volumio[3179]: info: Retrieving Cloud Streaming UI Feb 24 18:06:13 rivoplus volumio[3179]: info: Getting Tidal Cloud Configuration Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:06:13 rivoplus volumio[3179]: info: Getting Qobuz Cloud Configuration Feb 24 18:06:13 rivoplus volumio[3179]: info: Asking plugin for UI Config Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:06:13 rivoplus volumio[3179]: info: Getting Spotify Cloud Configuration Feb 24 18:06:13 rivoplus volumio[3179]: info: Asking plugin for UI Config Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:06:13 rivoplus volumio[3179]: info: Saving Spotify Acccount Feb 24 18:06:13 rivoplus volumio[3179]: info: Got it Feb 24 18:06:13 rivoplus volumio[3179]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 24 18:06:13 rivoplus volumio[3179]: info: Got Tidal Cloud Configuration Feb 24 18:06:13 rivoplus volumio[3179]: info: Got it Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 18:06:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 24 18:06:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 18:06:18 rivoplus volumio[3179]: info: Disabling MyMusic plugin multiroom Feb 24 18:06:18 rivoplus volumio[3179]: info: Disabling plugin multiroom Feb 24 18:06:18 rivoplus volumio[3179]: info: Preparing to generate the ALSA configuration file Feb 24 18:06:18 rivoplus volumio[3179]: info: Asound.conf file written Feb 24 18:06:18 rivoplus sudo[5801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 18:06:18 rivoplus sudo[5801]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:18 rivoplus sudo[5801]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:18 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 24 18:06:18 rivoplus kernel: Err: can not set rate to 4274967296! Feb 24 18:06:18 rivoplus kernel: Range[3920000 - 500000000] Feb 24 18:06:18 rivoplus kernel: out of value, fixed it Feb 24 18:06:18 rivoplus kernel: id=0 set inskew=0 Feb 24 18:06:18 rivoplus volumio[3179]: No protocol specified Feb 24 18:06:18 rivoplus volumio[3179]: xcb_connection_has_error() returned true Feb 24 18:06:18 rivoplus volumio[3179]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Feb 24 18:06:18 rivoplus volumio[3179]: Hardware is initialized using a generic method Feb 24 18:06:18 rivoplus volumio[3179]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 24 18:06:18 rivoplus volumio[3179]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 24 18:06:18 rivoplus volumio[3179]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 24 18:06:18 rivoplus volumio[3179]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 24 18:06:18 rivoplus volumio[3179]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Feb 24 18:06:18 rivoplus volumio[3179]: info: Output device has changed, restarting MPD Feb 24 18:06:18 rivoplus sudo[5806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 18:06:18 rivoplus sudo[5806]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:18 rivoplus volumio[3179]: info: Output device has changed, restarting Shairport Sync Feb 24 18:06:18 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:18 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:06:18 rivoplus sudo[5806]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:18 rivoplus sudo[5809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 18:06:18 rivoplus sudo[5809]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:18 rivoplus systemd[1]: Stopping Music Player Daemon... Feb 24 18:06:18 rivoplus volumio[3179]: No protocol specified Feb 24 18:06:18 rivoplus volumio[3179]: xcb_connection_has_error() returned true Feb 24 18:06:18 rivoplus systemd[1]: mpd.service: Succeeded. Feb 24 18:06:19 rivoplus systemd[1]: Stopped Music Player Daemon. Feb 24 18:06:19 rivoplus volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 18:06:19 rivoplus volumio[3179]: No protocol specified Feb 24 18:06:19 rivoplus volumio[3179]: xcb_connection_has_error() returned true Feb 24 18:06:19 rivoplus volumio[3179]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus systemd[1]: Starting Music Player Daemon... Feb 24 18:06:19 rivoplus volumio[3179]: info: QobuzConnect: setDeactiveState invoked Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:19 rivoplus volumio[3179]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus vtcs[4587]: [2026-02-24 18:06:19.045] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 24 18:06:19 rivoplus vtcs[4587]: [2026-02-24 18:06:19.045] [tisoc] [error] [SpkconServer.cpp:476] socket write error. fd=3 errorno=32 error=Broken pipe Feb 24 18:06:19 rivoplus vtcs[4587]: [2026-02-24 18:06:19.046] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 24 18:06:19 rivoplus volumio[3179]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 24 18:06:19 rivoplus volumio[3179]: info: Done. Feb 24 18:06:19 rivoplus sudo[5827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 18:06:19 rivoplus sudo[5827]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Feb 24 18:06:19 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 24 18:06:19 rivoplus systemd[1]: vtcs.service: Succeeded. Feb 24 18:06:19 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Feb 24 18:06:19 rivoplus sudo[5830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 18:06:19 rivoplus sudo[5830]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus sudo[5827]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus sudo[5815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 18:06:19 rivoplus sudo[5815]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus sudo[5830]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus sudo[5815]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:19 rivoplus sudo[5840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 18:06:19 rivoplus sudo[5840]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus sudo[5840]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus volumio[3179]: info: MPD Permissions set Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus sudo[5845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 18:06:19 rivoplus sudo[5845]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus qobuz-connect[4504]: 20260224 18:06:19.325 [4504.4504] INFO SampleApp: Stopping Local configuration server Feb 24 18:06:19 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 24 18:06:19 rivoplus qobuz-connect[4504]: 20260224 18:06:19.336 [4504.4504] INFO SampleApp: shat down connection on UNIX socket Feb 24 18:06:19 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Feb 24 18:06:19 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 24 18:06:19 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Feb 24 18:06:19 rivoplus sudo[5845]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 18:06:19 rivoplus volumio[3179]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 18:06:19 rivoplus volumio[3179]: info: QobuzConnect: setDeactiveState invoked Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:19 rivoplus volumio[3179]: info: Starting Shairport Sync Feb 24 18:06:19 rivoplus volumio[3179]: info: Executing endpoint qc_getconfig Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 18:06:19 rivoplus sudo[5859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 18:06:19 rivoplus sudo[5859]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 18:06:19 rivoplus systemd[1]: shairport-sync.service: Succeeded. Feb 24 18:06:19 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.508 [5853.5853] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 18:06:19 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.515 [5853.5853] INFO VolumeManager: [0xab38e240]: Setting new playback volume: 75 Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.517 [5853.5853] INFO VolumeManager: [0xab38e240]: Setting new mute state: 0 Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.517 [5853.5853] INFO QobuzConnect: [0xab38ec10]: Client initialized! Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.517 [5853.5853] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Feb 24 18:06:19 rivoplus sudo[5859]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 18:06:19 rivoplus volumio[3179]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 18:06:19 rivoplus volumio[3179]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.557 [5853.5853] INFO LocalConfigManager: [0xab38db20]: Starting Local Configuration server Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.557 [5853.5853] INFO SampleApp: Starting Local configuration server Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.558 [5853.5853] INFO SampleApp: Connected to UNIX socket client 0xab383ed8 Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: Not Reporting Auto name since its the default one Feb 24 18:06:19 rivoplus volumio[3179]: info: Shairport-Sync Started Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:06:19 rivoplus qobuz-connect[5853]: 20260224 18:06:19.657 [5853.5853] INFO SampleApp: Playback volume changed: 75 Feb 24 18:06:19 rivoplus volumio[3179]: info: Not Reporting Auto name since its the default one Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 24 18:06:19 rivoplus volumio[3179]: No protocol specified Feb 24 18:06:19 rivoplus volumio[3179]: xcb_connection_has_error() returned true Feb 24 18:06:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 24 18:06:19 rivoplus sudo[5879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 24 18:06:19 rivoplus sudo[5879]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus sudo[5879]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus sudo[5883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 24 18:06:19 rivoplus sudo[5883]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus volumio[3179]: No protocol specified Feb 24 18:06:19 rivoplus volumio[3179]: xcb_connection_has_error() returned true Feb 24 18:06:19 rivoplus systemd[1]: Stopping RAAT DAEMON... Feb 24 18:06:19 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 24 18:06:19 rivoplus systemd[1]: raat-daemon.service: Succeeded. Feb 24 18:06:19 rivoplus systemd[1]: Stopped RAAT DAEMON. Feb 24 18:06:19 rivoplus systemd[1]: Started RAAT DAEMON. Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:19 rivoplus sudo[5883]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 18:06:19 rivoplus volumio[3179]: info: Raat Daemon started successfully Feb 24 18:06:19 rivoplus sudo[5886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 24 18:06:19 rivoplus sudo[5886]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:19 rivoplus sudo[5886]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:19 rivoplus sudo[5895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 24 18:06:19 rivoplus sudo[5895]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:20 rivoplus systemd[1]: Stopping RAAT DAEMON... Feb 24 18:06:20 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 24 18:06:20 rivoplus systemd[1]: raat-daemon.service: Succeeded. Feb 24 18:06:20 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:20 rivoplus systemd[1]: Stopped RAAT DAEMON. Feb 24 18:06:20 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 18:06:20 rivoplus systemd[1]: Started RAAT DAEMON. Feb 24 18:06:20 rivoplus sudo[5895]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:20 rivoplus volumio[3179]: info: Raat Daemon started successfully Feb 24 18:06:20 rivoplus volumio[3179]: info: Executing endpoint restartRAATSocket Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 24 18:06:20 rivoplus volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:20 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:20 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:20 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Feb 24 18:06:20 rivoplus volumio[3179]: verbose: STATE SERVICE {"status":"stop","service":"raat","title":"First We Take Manhattan (Digitally Remastered)","artist":"Jennifer Warnes","album":"Famous Blue Raincoat. Songs of Leonard Cohen","albumart":"/albumart","uri":"","trackType":"rr","seek":null,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1771923926684,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"codec":"","repeatMode":"all"} Feb 24 18:06:20 rivoplus volumio[3179]: verbose: CURRENT POSITION 0 Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreStateMachine::syncState stateService stop Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:20 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:20 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:20 rivoplus volumio[3179]: info: No code Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:20 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:20 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:20 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:20 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:20.683+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:20 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:20.683+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:20 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:20.685+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:20 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:20.685+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:20 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:20.686+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:20 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:20.686+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:20 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:20 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:20 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:21 rivoplus mpd[5842]: Feb 24 18:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 18:06:21 rivoplus systemd[1]: Started Music Player Daemon. Feb 24 18:06:21 rivoplus sudo[5809]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:21 rivoplus volumio[3179]: error: updateQueue error: null Feb 24 18:06:21 rivoplus volumio[3179]: info: Disabling MyMusic plugin bluetooth Feb 24 18:06:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 18:06:21 rivoplus sudo[5917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Feb 24 18:06:21 rivoplus sudo[5917]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:21 rivoplus systemd[1]: Stopping Volumio Bluetooth Module... Feb 24 18:06:21 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:21 rivoplus volumiobt[5919]: Killing pulse Feb 24 18:06:21 rivoplus volumiobt[5919]: Killing BT Pair agent Feb 24 18:06:21 rivoplus bluetoothd[3306]: Endpoint unregistered: sender=:1.47 path=/MediaEndpoint/A2DPSource Feb 24 18:06:21 rivoplus bluetoothd[3306]: Endpoint unregistered: sender=:1.47 path=/MediaEndpoint/A2DPSink Feb 24 18:06:21 rivoplus systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Feb 24 18:06:21 rivoplus systemd[1]: volumiobt.service: Succeeded. Feb 24 18:06:21 rivoplus systemd[1]: Stopped Volumio Bluetooth Module. Feb 24 18:06:21 rivoplus sudo[5917]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:21 rivoplus volumio[3179]: info: Volumio BT Module successfully stopped Feb 24 18:06:21 rivoplus volumio[3179]: info: Disabling plugin bluetooth Feb 24 18:06:21 rivoplus volumio[3179]: info: Done. Feb 24 18:06:22 rivoplus volumio[3179]: info: TidalConnect service stoped! Feb 24 18:06:22 rivoplus volumio[3179]: info: TidalConnect service stoped! Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 18:06:22 rivoplus sudo[5931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 18:06:22 rivoplus sudo[5931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:22 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Feb 24 18:06:22 rivoplus sudo[5931]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:22 rivoplus volumio[3179]: info: Executing endpoint tc_getconfig Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 24 18:06:22 rivoplus vtcs[5933]: STARTING TidalConnect services, version: 1.6.1 Feb 24 18:06:22 rivoplus vtcs[5933]: STARTED TidalConnect services. Feb 24 18:06:22 rivoplus volumio[3179]: info: Executing endpoint tc_connect Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 24 18:06:22 rivoplus volumio[3179]: info: Connecting to TidalConnect Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:22 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Feb 24 18:06:22 rivoplus volumio[3179]: 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} Feb 24 18:06:22 rivoplus volumio[3179]: verbose: CURRENT POSITION 0 Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::syncState stateService stop Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:22 rivoplus volumio[3179]: info: No code Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:22 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Feb 24 18:06:22 rivoplus volumio[3179]: 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} Feb 24 18:06:22 rivoplus volumio[3179]: verbose: CURRENT POSITION 0 Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::syncState stateService stop Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:22 rivoplus volumio[3179]: info: No code Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:22 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.744+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.744+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.744+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.745+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.745+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.746+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.748+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.748+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.749+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.750+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.750+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:22 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:22.751+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:22 rivoplus volumio[3179]: info: RAAT: Requesting Headphone Status Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:22 rivoplus volumio[3179]: info: MCU Signalled Headphone Mode Disabled Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 24 18:06:22 rivoplus volumio[3179]: info: Reporting Headphone State: false Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:22 rivoplus volumio[3179]: info: RAAT: Requesting Headphone Status Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 24 18:06:22 rivoplus volumio[3179]: info: MCU Signalled Headphone Mode Disabled Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 24 18:06:22 rivoplus volumio[3179]: info: Reporting Headphone State: false Feb 24 18:06:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:22 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:23 rivoplus volumio[3179]: info: MRS: STARTING volumioStreaming Feb 24 18:06:23 rivoplus sudo[5953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 24 18:06:23 rivoplus sudo[5953]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:23 rivoplus sudo[5953]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:23 rivoplus sudo[5956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 24 18:06:23 rivoplus sudo[5956]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:23 rivoplus systemd[1]: Stopping VolumioStreamingService... Feb 24 18:06:23 rivoplus systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Feb 24 18:06:23 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:23 rivoplus systemd[1]: volumioStreaming.service: Succeeded. Feb 24 18:06:23 rivoplus systemd[1]: Stopped VolumioStreamingService. Feb 24 18:06:23 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 18:06:23 rivoplus systemd[1]: Started VolumioStreamingService. Feb 24 18:06:23 rivoplus sudo[5956]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:23 rivoplus volumio[3179]: info: MRS: volumioStreaming STARTED Feb 24 18:06:23 rivoplus sudo[5959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 24 18:06:23 rivoplus sudo[5959]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:23 rivoplus sudo[5959]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:24 rivoplus volumio[3179]: info: Disabling MyMusic plugin metavolumio Feb 24 18:06:24 rivoplus volumio[3179]: info: Removing METAVOLUMIO REST API Endpoints Feb 24 18:06:24 rivoplus volumio[3179]: info: Removing metavolumio REST Endpoint Feb 24 18:06:24 rivoplus volumio[3179]: info: Removing getSimilarArtists REST Endpoint Feb 24 18:06:24 rivoplus volumio[3179]: info: Removing getSimilarAlbums REST Endpoint Feb 24 18:06:24 rivoplus volumio[3179]: info: Removing getSimilarTracks REST Endpoint Feb 24 18:06:24 rivoplus volumio[3179]: info: Disabling plugin metavolumio Feb 24 18:06:24 rivoplus volumio[3179]: info: Done. Feb 24 18:06:25 rivoplus volumio[3179]: info: TidalConnect service started! Feb 24 18:06:25 rivoplus volumio[3179]: info: Disabling MyMusic plugin airplay_emulation Feb 24 18:06:25 rivoplus volumio[3179]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Feb 24 18:06:25 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 18:06:25 rivoplus volumio[3179]: info: Disabling plugin airplay_emulation Feb 24 18:06:25 rivoplus volumio[3179]: info: Done. Feb 24 18:06:25 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 18:06:25 rivoplus sudo[5964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Feb 24 18:06:25 rivoplus sudo[5964]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:25 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 18:06:25 rivoplus systemd[1]: shairport-sync.service: Succeeded. Feb 24 18:06:25 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:25 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 18:06:25 rivoplus sudo[5964]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:25 rivoplus volumio[3179]: info: Shairport-Sync Stopped Feb 24 18:06:27 rivoplus volumio[3179]: info: Disabling MyMusic plugin cd_controller Feb 24 18:06:27 rivoplus volumio[3179]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Feb 24 18:06:27 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 18:06:27 rivoplus volumio[3179]: info: Stopping UDEV Watcher for CD Feb 24 18:06:27 rivoplus volumio[3179]: info: Disabling plugin cd_controller Feb 24 18:06:27 rivoplus volumio[3179]: info: Done. Feb 24 18:06:27 rivoplus volumio[3179]: info: Disabling MyMusic plugin qobuzconnect Feb 24 18:06:27 rivoplus volumio[3179]: info: QobuzConnect: setDeactiveState invoked Feb 24 18:06:27 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:27 rivoplus qobuz-connect[5853]: 20260224 18:06:27.510 [5853.5853] INFO MediaEngine: [0xab38ddd8]: Stopping playback, keeping tracks Feb 24 18:06:27 rivoplus sudo[5967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop qobuz-connect Feb 24 18:06:27 rivoplus sudo[5967]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:27 rivoplus qobuz-connect[5853]: 20260224 18:06:27.587 [5853.5853] INFO SampleApp: Stopping Local configuration server Feb 24 18:06:27 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 24 18:06:27 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:27 rivoplus qobuz-connect[5853]: 20260224 18:06:27.597 [5853.5853] INFO SampleApp: shat down connection on UNIX socket Feb 24 18:06:27 rivoplus volumio[3179]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 18:06:27 rivoplus volumio[3179]: info: QobuzConnect: setDeactiveState invoked Feb 24 18:06:27 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:27 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Feb 24 18:06:27 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 24 18:06:27 rivoplus sudo[5967]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:27 rivoplus volumio[3179]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket disconnected and tore down Feb 24 18:06:27 rivoplus volumio[3179]: info: Disabling plugin qobuzconnect Feb 24 18:06:27 rivoplus volumio[3179]: info: Done. Feb 24 18:06:28 rivoplus volumio[3179]: info: Disabling MyMusic plugin raat Feb 24 18:06:28 rivoplus volumio[3179]: info: Stopping RAAT Plugin Feb 24 18:06:28 rivoplus sudo[5987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Feb 24 18:06:28 rivoplus sudo[5987]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:28 rivoplus systemd[1]: Stopping RAAT DAEMON... Feb 24 18:06:28 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 24 18:06:28 rivoplus systemd[1]: raat-daemon.service: Succeeded. Feb 24 18:06:28 rivoplus volumio[3179]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 18:06:28 rivoplus systemd[1]: Stopped RAAT DAEMON. Feb 24 18:06:28 rivoplus sudo[5987]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:28 rivoplus volumio[3179]: info: Raat Daemon stopped successfully Feb 24 18:06:28 rivoplus volumio[3179]: info: Disabling plugin raat Feb 24 18:06:28 rivoplus volumio[3179]: info: Done. Feb 24 18:06:29 rivoplus volumio[3179]: info: Enabling MyMusic plugin raat Feb 24 18:06:29 rivoplus volumio[3179]: info: Enabling plugin raat Feb 24 18:06:29 rivoplus volumio[3179]: info: Loading plugin "raat"... Feb 24 18:06:29 rivoplus volumio[3179]: info: RAAT Plugin loaded Feb 24 18:06:29 rivoplus volumio[3179]: info: Adding restartRAATSocket REST API Endpoint Feb 24 18:06:29 rivoplus volumio[3179]: info: Updating restartRAATSocket REST Endpoint for plugin: music_service/raat Feb 24 18:06:29 rivoplus volumio[3179]: info: PLUGIN START: raat Feb 24 18:06:29 rivoplus volumio[3179]: info: Starting RAAT Plugin Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 24 18:06:29 rivoplus volumio[3179]: info: Registering DSP Elements listener and retrieving current ones Feb 24 18:06:29 rivoplus volumio[3179]: info: Additional DSP elements updated Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:29 rivoplus volumio[3179]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:06:29 rivoplus volumio[3179]: info: Not Reporting Auto name since its the default one Feb 24 18:06:29 rivoplus volumio[3179]: info: Done. Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 24 18:06:29 rivoplus volumio[3179]: No protocol specified Feb 24 18:06:29 rivoplus volumio[3179]: xcb_connection_has_error() returned true Feb 24 18:06:29 rivoplus sudo[5997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 24 18:06:29 rivoplus sudo[5997]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:29 rivoplus sudo[5997]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:29 rivoplus sudo[5999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 24 18:06:29 rivoplus sudo[5999]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:29 rivoplus systemd[1]: Started RAAT DAEMON. Feb 24 18:06:29 rivoplus sudo[5999]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:29 rivoplus volumio[3179]: info: Raat Daemon started successfully Feb 24 18:06:29 rivoplus volumio[3179]: info: Executing endpoint restartRAATSocket Feb 24 18:06:29 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 24 18:06:29 rivoplus volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 24 18:06:29 rivoplus volumio[3179]: info: Enabling MyMusic plugin qobuzconnect Feb 24 18:06:29 rivoplus volumio[3179]: info: Enabling plugin qobuzconnect Feb 24 18:06:29 rivoplus volumio[3179]: info: Loading plugin "qobuzconnect"... Feb 24 18:06:29 rivoplus volumio[3179]: info: PLUGIN START: qobuzconnect Feb 24 18:06:29 rivoplus volumio[3179]: info: Updating qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 24 18:06:29 rivoplus volumio[3179]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 24 18:06:29 rivoplus volumio[3179]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 24 18:06:30 rivoplus sudo[6018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 18:06:30 rivoplus sudo[6018]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:30 rivoplus sudo[6018]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:30 rivoplus sudo[6020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 18:06:30 rivoplus sudo[6020]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:06:30 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Feb 24 18:06:30 rivoplus sudo[6020]: pam_unix(sudo:session): session closed for user root Feb 24 18:06:30 rivoplus volumio[3179]: info: Done. Feb 24 18:06:30 rivoplus volumio[3179]: info: Executing endpoint qc_getconfig Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.197 [6022.6022] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 18:06:30 rivoplus volumio[3179]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 18:06:30 rivoplus volumio[3179]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.204 [6022.6022] INFO VolumeManager: [0xac9c2240]: Setting new playback volume: 75 Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.204 [6022.6022] INFO VolumeManager: [0xac9c2240]: Setting new mute state: 0 Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.204 [6022.6022] INFO QobuzConnect: [0xac9c2c10]: Client initialized! Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.204 [6022.6022] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.216 [6022.6022] INFO LocalConfigManager: [0xac9c1b20]: Starting Local Configuration server Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.216 [6022.6022] INFO SampleApp: Starting Local configuration server Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.217 [6022.6022] INFO SampleApp: Connected to UNIX socket client 0xac9b7ed8 Feb 24 18:06:30 rivoplus qobuz-connect[6022]: 20260224 18:06:30.347 [6022.6022] INFO SampleApp: Playback volume changed: 75 Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:30 rivoplus volumio[3179]: [VRCP] RAAT stopped playing Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:30 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:30 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:30 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Feb 24 18:06:30 rivoplus volumio[3179]: verbose: STATE SERVICE {"status":"stop","service":"raat","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":null,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":false,"push_time":0,"random":false,"repeat":false,"repeatSingle":false} Feb 24 18:06:30 rivoplus volumio[3179]: verbose: CURRENT POSITION 0 Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreStateMachine::syncState stateService stop Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:30 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:30 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:30 rivoplus volumio[3179]: info: No code Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:30 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:30 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:30 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:30 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:30.458+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:30 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:30.459+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:30 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:30.460+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:30 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:30.460+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:30 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:30.461+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:30 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:30.461+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:30 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:30 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:30 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:32 rivoplus volumio[3179]: info: RAAT: Requesting Headphone Status Feb 24 18:06:32 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 24 18:06:32 rivoplus volumio[3179]: info: MCU Signalled Headphone Mode Disabled Feb 24 18:06:32 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 24 18:06:32 rivoplus volumio[3179]: info: Reporting Headphone State: false Feb 24 18:06:32 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:32 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 18:06:32 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.241 [6022.6044] INFO SampleApp: API endpoint invoked: get-connect-info Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.922 [6022.6044] INFO SampleApp: API endpoint invoked: connect-to-qconnect Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.923 [6022.6022] INFO EndpointManager: [0xac9c1750]: Updating API endpoint Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.923 [6022.6022] INFO EndpointManager: [0xac9c1750]: Updating QConnect endpoint Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.923 [6022.6022] INFO ActiveStateManager: [0xac9c0768]: Setting new active state: active Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.923 [6022.6022] INFO PlaybackSessionManager: [0xac9c1b40]: Starting playback session maintenance Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.923 [6022.6022] INFO HttpDownloader: [0xac9c1d30]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.923 [6022.6022] INFO CloudClient: [0xac9c23b0]: Connecting to the cloud Feb 24 18:06:41 rivoplus qobuz-connect[6022]: 20260224 18:06:41.924 [6022.6022] INFO SampleApp: Renderer is now active Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.109 [6022.6022] INFO CloudClient: [0xac9c23b0]: Connection established Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.109 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Authenticate message Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.109 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Subscribe message Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.109 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending JoinSession message Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.109 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending VolumeChanged message Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.109 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending VolumeMuted message Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.110 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending MaxAudioQualityChanged message Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.110 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Received SetActive message: active Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Received SetState message: Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Playing state: Paused Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Playback position: 0 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Queue version: 1.1 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Current track: TID: 383208726, QID: 0, Context UUID: 45842bc6-b672-4298-90a7-33ce6dfce706 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Next track: TID: 383208727, QID: 1, Context UUID: 45842bc6-b672-4298-90a7-33ce6dfce706 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Stopping playback, clearing tracks Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Initiating playback Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.167 [6022.6022] INFO RendererActionAvailabilityManager: [0xac9c2468]: Renderer action 'Next' is available Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Received SetLoopMode message: Off Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO PlaybackControlsManager: [0xac9c21b0]: Setting new loop mode: Off Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Setting current track: 383208726, initial offset: 0ms Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Clearing all streams Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: New stream: 1 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO HttpDownloader: [0xaca9d250]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=c01b54337f305190a07a1397b660bb70&request_ts=1771924002&track_id=383208726 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO HttpDownloader: [0xaca9e1e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=383208726 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 1]: Running audio stream Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Received SetShuffleMode message: disabled Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.168 [6022.6022] INFO PlaybackControlsManager: [0xac9c21b0]: Setting new shuffle mode: disabled Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.169 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Setting next track: 383208727 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.169 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: New stream: 2 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.169 [6022.6022] INFO HttpDownloader: [0xacb625f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b1088f1e7dd506d208ebeb78d6e7266e&request_ts=1771924002&track_id=383208727 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.170 [6022.6022] INFO HttpDownloader: [0xacb63588]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=383208727 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.170 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Waiting for current stream to start before starting audio renderer Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.533 [6022.6022] INFO PlaybackSessionManager: [0xac9c1b40]: Playback session has been refreshed Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.533 [6022.6022] INFO HttpDownloader: [0xac9cde98]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1d5914b843b52c27a141fdd3562a20f2&request_ts=1771924002&track_id=383208726 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.533 [6022.6022] INFO HttpDownloader: [0xacb6dd68]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=73e4ad23bd0bfc5eb25773e3aa0e1cc9&request_ts=1771924002&track_id=383208727 Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.660 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=383208726&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927602&hmac=LfWQlYrfiFOwYYjzsxVbKk0a7vU Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.687 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=383208727&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927602&hmac=ZClx1QfIYcdeC_qqhtxLjAObnNc Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.735 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 2]: Metadata became available: Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.736 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Title: Linger Long Enough Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.736 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Artist: Good Harvest Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.736 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album: In a Life and Place Like This Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.736 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album art URL: https://static.qobuz.com/images/covers/h3/kj/wm7f98qqdkjh3_600.jpg Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.798 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 1]: Metadata became available: Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.799 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Title: Pilgrim Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.799 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Artist: Good Harvest Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.799 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album: In a Life and Place Like This Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.799 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album art URL: https://static.qobuz.com/images/covers/h3/kj/wm7f98qqdkjh3_600.jpg Feb 24 18:06:42 rivoplus qobuz-connect[6022]: 20260224 18:06:42.968 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 2]: stream information have been fetched Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.000 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 1]: stream information have been fetched Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.000 [6022.6022] INFO UrlAudioSource: [0xacaa4230]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=383208726&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927602&hmac=LfWQlYrfiFOwYYjzsxVbKk0a7vU Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.000 [6022.6022] INFO ContentFetcher: [0xac9cbef8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=383208726&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927602&hmac=LfWQlYrfiFOwYYjzsxVbKk0a7vU, offset: 0 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.001 [6022.6022] INFO AudioRenderer: [0xac9c1ff8]: Starting audio renderer, initial playback state: Paused Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.001 [6022.6022] INFO SampleApp: [Stream 1]: New audio stream (starting from 0ms) Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.001 [6022.6022] INFO SampleApp: [Stream 1]: Stream metadata became available: Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.001 [6022.6022] INFO SampleApp: Title: Pilgrim Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.001 [6022.6022] INFO SampleApp: Artist: Good Harvest Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.001 [6022.6022] INFO SampleApp: Album: In a Life and Place Like This Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.001 [6022.6022] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/h3/kj/wm7f98qqdkjh3_600.jpg Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: QobuzConnect: setActiveState() Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: QobuzConnect: Set Qobuz connect default volume to 100 Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.009 [6022.6022] INFO VolumeManager: [0xac9c2240]: Setting new playback volume: 100 Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: QobuzConnect: Stopping currently active service Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioStop Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::stop Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::serviceStop Feb 24 18:06:43 rivoplus volumio[3179]: info: Received STOP, but no service to execute it Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:43 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Feb 24 18:06:43 rivoplus volumio[3179]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false} Feb 24 18:06:43 rivoplus volumio[3179]: verbose: CURRENT POSITION 0 Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::syncState stateService stop Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:43 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: No code Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:06:43 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Feb 24 18:06:43 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:43.044+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:43 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:43.044+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:43 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:43.046+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:43 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:43.046+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:43 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:43.046+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:06:43 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:06:43.047+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:06:43 rivoplus volumio[3179]: info: QobuzConnect: setActiveState() completed, send playback confirmation Feb 24 18:06:43 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:43 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:43 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.090 [6022.6022] INFO SampleApp: Initiating playback, initial state: Paused Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.175 [6022.6022] INFO SampleApp: Playback volume changed: 100 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.175 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Volume changed: 100 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.175 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending VolumeChanged message Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.176 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.176 [6022.6022] INFO SampleApp: Playback state changed: Paused Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.176 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Need to send state update Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.176 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending StateUpdated message Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.176 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.176 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending FileAudioQualityChanged message Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.177 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.177 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending DeviceAudioQualityChanged message Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.177 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.195 [6022.6022] INFO UrlAudioSource: [0xacaa4230]: Content info extracted Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.195 [6022.6022] INFO UrlAudioSource: [0xacaa4230]: Size: 27003431 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.196 [6022.6049] INFO AudioDecoder: [0xacc34208]: Stream info: Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.197 [6022.6049] INFO AudioDecoder: [0xacc34208]: Total samples: 11623584 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.197 [6022.6049] INFO AudioDecoder: [0xacc34208]: Sample rate: 44100 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.198 [6022.6049] INFO AudioDecoder: [0xacc34208]: Channel count: 2 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.198 [6022.6049] INFO AudioDecoder: [0xacc34208]: Bits per sample: 16 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.198 [6022.6049] INFO AudioDecoder: [0xacc34208]: Duration: 263573 Feb 24 18:06:43 rivoplus qobuz-connect[6022]: 20260224 18:06:43.199 [6022.6022] INFO UrlAudioSource: [0xacaa4230]: Audio properties have changed Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.029 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Received SetState message: Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.029 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Playing state: Playing Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Playback position: 0 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Queue version: 2.1 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Current track: TID: 30477126, QID: 0, Context UUID: e580784c-24ce-4f8d-8127-ce2fd2161358 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Next track: TID: 30477127, QID: 1, Context UUID: e580784c-24ce-4f8d-8127-ce2fd2161358 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Stopping playback, clearing tracks Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Initiating playback Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO AudioRenderer: [0xac9c1ff8]: Stopping audio renderer Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.030 [6022.6022] INFO SampleApp: Stopping playback Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.057 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Clearing all streams Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.057 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 1]: Disposing of audio stream Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.057 [6022.6022] INFO ContentFetcher: [0xac9cbef8]: Canceling download Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.064 [6022.6022] INFO SampleApp: Playback state changed: Stopped Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Need to send state update Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending StateUpdated message Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Setting current track: 30477126, initial offset: 0ms Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Clearing all streams Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: New stream: 3 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO HttpDownloader: [0xacb62278]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=5d300102238b437fa53ea2e7fb65b71d&request_ts=1771924023&track_id=30477126 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.065 [6022.6022] INFO HttpDownloader: [0xacb6ddf8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=30477126 Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.066 [6022.6022] INFO HttpDownloader: [0xac9c6788]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=0ee4515434c20d11b9e503a64074b4b7&request_ts=1771924023&track_id=30477126 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.066 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 3]: Running audio stream Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.066 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.068 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Setting next track: 30477127 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.068 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: New stream: 4 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.068 [6022.6022] INFO HttpDownloader: [0xac9f62b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=62c521e6391f2b528102f3322412fd4f&request_ts=1771924023&track_id=30477127 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.068 [6022.6022] INFO HttpDownloader: [0xacc33ba8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=30477127 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.068 [6022.6022] INFO HttpDownloader: [0xacb61fc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=9b01abe66f44d97e286836d2f80107c6&request_ts=1771924023&track_id=30477127 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.069 [6022.6022] INFO MediaEngine: [0xac9c1dd8]: Waiting for current stream to start before starting audio renderer Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.393 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=30477126&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927623&hmac=K8bP8mNm2PkYgd5vl2TlWbssCbE Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.421 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=30477127&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927623&hmac=YiE0L2MFsAO4vLgvUAPtYxc37ic Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.437 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 4]: stream information have been fetched Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.469 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 3]: Metadata became available: Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.469 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Title: First We Take Manhattan (Digitally Remastered) Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.469 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Artist: スティーヴィー・レイ・ヴォーン Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.469 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album: Famous Blue Raincoat. Songs of Leonard Cohen (Digitally Remastered) Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.469 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album art URL: https://static.qobuz.com/images/covers/05/49/0826663104905_600.jpg Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.523 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 4]: Metadata became available: Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.524 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Title: Bird on a Wire (Digitally Remastered) Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.524 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Artist: Jennifer Warnes Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.524 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album: Famous Blue Raincoat. Songs of Leonard Cohen (Digitally Remastered) Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.524 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: Album art URL: https://static.qobuz.com/images/covers/05/49/0826663104905_600.jpg Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.524 [6022.6022] INFO AudioStreamManager: [0xac9c1ec0]: [Stream 3]: stream information have been fetched Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.524 [6022.6022] INFO UrlAudioSource: [0xacb92f30]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=30477126&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927623&hmac=K8bP8mNm2PkYgd5vl2TlWbssCbE Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.524 [6022.6022] INFO ContentFetcher: [0xacba0000]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3324104&eid=30477126&fmt=6&profile=raw&app_id=174516466&cid=2506629&etsp=1771927623&hmac=K8bP8mNm2PkYgd5vl2TlWbssCbE, offset: 0 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.525 [6022.6022] INFO AudioRenderer: [0xac9c1ff8]: Starting audio renderer, initial playback state: Playing Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.525 [6022.6022] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.525 [6022.6022] INFO SampleApp: [Stream 3]: Stream metadata became available: Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.525 [6022.6022] INFO SampleApp: Title: First We Take Manhattan (Digitally Remastered) Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.525 [6022.6022] INFO SampleApp: Artist: スティーヴィー・レイ・ヴォーン Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.525 [6022.6022] INFO SampleApp: Album: Famous Blue Raincoat. Songs of Leonard Cohen (Digitally Remastered) Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.525 [6022.6022] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/05/49/0826663104905_600.jpg Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: QobuzConnect: setActiveState() Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: QobuzConnect: Set Qobuz connect default volume to 100 Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.529 [6022.6022] INFO VolumeManager: [0xac9c2240]: Setting new playback volume: 100 Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: QobuzConnect: Stopping currently active service Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioStop Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::stop Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::serviceStop Feb 24 18:07:03 rivoplus volumio[3179]: info: Received STOP, but no service to execute it Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Feb 24 18:07:03 rivoplus volumio[3179]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false} Feb 24 18:07:03 rivoplus volumio[3179]: verbose: CURRENT POSITION 0 Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::syncState stateService stop Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: No code Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.561+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.561+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.563+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.563+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.564+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_STOPPED positionMs= volume=100 Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.564+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:07:03 rivoplus volumio[3179]: info: QobuzConnect: setActiveState() completed, send playback confirmation Feb 24 18:07:03 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:07:03 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:07:03 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.601 [6022.6022] INFO SampleApp: Initiating playback, initial state: Playing Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.654 [6022.6022] INFO SampleApp: Playback state changed: Playing Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.654 [6022.6022] INFO StreamingEventReporter: [0xac9c2930]: Reporting streaming started event Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.654 [6022.6022] INFO HttpDownloader: [0xac9c2988]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.654 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Need to send state update Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.654 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending StateUpdated message Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.655 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.656 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending FileAudioQualityChanged message Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.656 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.668 [6022.6022] INFO VolumeManager: [0xac9c2240]: Setting new playback volume: 100 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.668 [6022.6022] INFO VolumeManager: [0xac9c2240]: Setting new mute state: 0 Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.683 [6022.6022] INFO UrlAudioSource: [0xacb92f30]: Content info extracted Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.683 [6022.6022] INFO UrlAudioSource: [0xacb92f30]: Size: 24501330 Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.685 [6022.6126] INFO AudioDecoder: [0xacb611b8]: Stream info: Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.685 [6022.6126] INFO AudioDecoder: [0xacb611b8]: Total samples: 10008348 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.686 [6022.6126] INFO AudioDecoder: [0xacb611b8]: Sample rate: 44100 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.686 [6022.6126] INFO AudioDecoder: [0xacb611b8]: Channel count: 2 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.686 [6022.6126] INFO AudioDecoder: [0xacb611b8]: Bits per sample: 16 Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.686 [6022.6126] INFO AudioDecoder: [0xacb611b8]: Duration: 226946 Feb 24 18:07:03 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:07:03 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:03 rivoplus qobuz-connect[6022]: 20260224 18:07:03.686 [6022.6022] INFO UrlAudioSource: [0xacb92f30]: Audio properties have changed Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.691+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.691+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title= Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.692+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.693+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.695+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:07:03 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:03.696+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:07:03 rivoplus volumio[3179]: info: Signalling Playback active due to playback status change Feb 24 18:07:03 rivoplus volumio[3179]: info: Signalling Playback active due to playback status change Feb 24 18:07:03 rivoplus volumio[3179]: info: Signalling Playback active due to playback status change Feb 24 18:07:03 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:07:03 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:07:03 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:07:03 rivoplus volumio[3179]: info: MCU Signalled Playback Active Feb 24 18:07:13 rivoplus qobuz-connect[6022]: 20260224 18:07:13.654 [6022.6022] INFO ProtocolHandler: [0xac9c2518]: Need to send state update Feb 24 18:07:13 rivoplus qobuz-connect[6022]: 20260224 18:07:13.655 [6022.6022] INFO QConnectMessageSender: [0xacb4ccd8]: Sending StateUpdated message Feb 24 18:07:13 rivoplus qobuz-connect[6022]: 20260224 18:07:13.655 [6022.6022] INFO QwspMessageSender: [0xacb4ccc8]: Sending Payload message Feb 24 18:07:13 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:13 rivoplus volumio[3179]: info: CoreCommandRouter::servicePushState Feb 24 18:07:13 rivoplus volumio[3179]: info: CoreStateMachine::pushState Feb 24 18:07:13 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:07:13 rivoplus volumio[3179]: info: CoreCommandRouter::volumioPushState Feb 24 18:07:13 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:13 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output update for this device Feb 24 18:07:13 rivoplus volumio[3179]: info: MRS: Pushing multiroomSync output Feb 24 18:07:13 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:13 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:13.668+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" state=STATUS_PLAYING positionMs=9966 volume=100 Feb 24 18:07:13 rivoplus volumio5-onboarding[3838]: time=2026-02-24T18:07:13.668+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.12:56223 @ 0x28300c0" id= title="First We Take Manhattan (Digitally Remastered)" Feb 24 18:07:13 rivoplus volumio[3179]: info: Signalling Playback active due to playback status change Feb 24 18:07:13 rivoplus volumio[3179]: info: Updating RAAT Signal Path Feb 24 18:07:16 rivoplus volumio[3179]: verbose: New Socket.io Connection to 192.168.0.20 from 192.168.0.12 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: 8 Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 18:07:16 rivoplus volumio[3179]: info: Received Get System Info Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 18:07:16 rivoplus volumio[3179]: info: Discovery: Getting this device information Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Feb 24 18:07:16 rivoplus volumio[3179]: info: Listing playlists Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetQueue Feb 24 18:07:16 rivoplus volumio[3179]: info: CoreStateMachine::getQueue Feb 24 18:07:16 rivoplus volumio[3179]: info: CorePlayQueue::getQueue Feb 24 18:07:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 24 18:07:19 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Feb 24 18:07:19 rivoplus volumio[3179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:07:19 rivoplus volumio[3179]: TypeError: Cannot read property 'then' of undefined Feb 24 18:07:19 rivoplus volumio[3179]: at outputs.disableAudioOutput (/volumio/app/plugins/audio_interface/outputs/index.js:273:9) Feb 24 18:07:19 rivoplus volumio[3179]: at CoreCommandRouter.disableAudioOutput (/volumio/app/index.js:2257:30) Feb 24 18:07:19 rivoplus volumio[3179]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1455:26) Feb 24 18:07:19 rivoplus volumio[3179]: at Socket.emit (events.js:400:28) Feb 24 18:07:19 rivoplus volumio[3179]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 24 18:07:19 rivoplus volumio[3179]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Feb 24 18:07:19 rivoplus volumio[3179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:07:20 rivoplus sudo[6171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 18:06 Feb 24 18:07:20 rivoplus sudo[6171]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 02:06:11 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="6fcef4b36db553cbf88901935dbe83e3"