-- Logs begin at Sun 2025-05-18 12:54:34 HKT, end at Sun 2025-05-18 13:11:05 HKT. -- May 18 13:10:00 rivoplus volumio[3186]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages May 18 13:10:00 rivoplus volumio[3186]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) May 18 13:10:00 rivoplus volumio[3186]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) May 18 13:10:00 rivoplus volumio[3186]: info: Building wheels for collected packages: camilladsp May 18 13:10:00 rivoplus volumio[3186]: info: Running setup.py bdist_wheel for camilladsp: started May 18 13:10:01 rivoplus volumio[3186]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' May 18 13:10:01 rivoplus volumio[3186]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-bk4ht66e/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 May 18 13:10:01 rivoplus volumio[3186]: info: Successfully built camilladsp May 18 13:10:08 rivoplus volumio[3186]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 May 18 13:10:08 rivoplus volumio[3186]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-jdcoiw44 May 18 13:10:10 rivoplus nmbd[3060]: [2025/05/18 13:10:10.702169, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 18 13:10:10 rivoplus nmbd[3060]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 18 13:10:14 rivoplus volumio[3186]: info: CorePlayQueue::getTrack 146 May 18 13:10:14 rivoplus volumio[3186]: info: CorePlayQueue::getTrack 79 May 18 13:10:14 rivoplus volumio[3186]: info: Prefetching next song May 18 13:10:14 rivoplus volumio[3186]: info: [1747545014592] ControllerTidal::prefetch May 18 13:10:14 rivoplus volumio[3186]: info: Getting stream with soundQuality HI_RES May 18 13:10:14 rivoplus volumio[3186]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/299658279&soundQuality=HI_RES" May 18 13:10:14 rivoplus volumio[3186]: info: May 18 13:10:14 rivoplus volumio[3186]: ---------------------------- MPD announces system playlist update May 18 13:10:14 rivoplus volumio[3186]: info: Ignoring MPD Status Update May 18 13:10:14 rivoplus volumio[3186]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/299658279&soundQuality=HI_RES" took 7 milliseconds May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:10:14 rivoplus volumio[3186]: verbose: ControllerMpd::sendMpdCommand consume 1 May 18 13:10:14 rivoplus volumio[3186]: info: May 18 13:10:14 rivoplus volumio[3186]: ---------------------------- MPD announces system playlist update May 18 13:10:14 rivoplus volumio[3186]: info: Ignoring MPD Status Update May 18 13:10:14 rivoplus volumio[3186]: info: May 18 13:10:14 rivoplus volumio[3186]: ---------------------------- MPD announces state update: options May 18 13:10:14 rivoplus volumio[3186]: info: ------------------------------ 15ms May 18 13:10:14 rivoplus volumio[3186]: info: sendMpdCommand consume 1 took 7 milliseconds May 18 13:10:14 rivoplus volumio[3186]: info: ControllerMpd::getState May 18 13:10:14 rivoplus volumio[3186]: verbose: ControllerMpd::sendMpdCommand status May 18 13:10:14 rivoplus volumio[3186]: info: ------------------------------ 8ms May 18 13:10:14 rivoplus volumio[3186]: info: May 18 13:10:14 rivoplus volumio[3186]: ---------------------------- MPD announces state update: options May 18 13:10:14 rivoplus volumio[3186]: info: ControllerMpd::getState May 18 13:10:14 rivoplus volumio[3186]: verbose: ControllerMpd::sendMpdCommand status May 18 13:10:14 rivoplus volumio[3186]: info: sendMpdCommand status took 5 milliseconds May 18 13:10:14 rivoplus volumio[3186]: info: sendMpdCommand status took 2 milliseconds May 18 13:10:14 rivoplus volumio[3186]: verbose: ControllerMpd::parseState May 18 13:10:14 rivoplus volumio[3186]: verbose: ControllerMpd::parseState May 18 13:10:14 rivoplus volumio[3186]: info: ControllerMpd::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::servicePushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioPushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output update for this device May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output May 18 13:10:14 rivoplus volumio[3186]: info: CorePlayQueue::getTrack 146 May 18 13:10:14 rivoplus volumio[3186]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:10:14 rivoplus volumio[3186]: verbose: CURRENT POSITION 146 May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::syncState stateService stop May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::syncState currentStatus play May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioPushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output update for this device May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioPushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output update for this device May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output May 18 13:10:14 rivoplus volumio[3186]: info: ControllerMpd::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::servicePushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioPushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output update for this device May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output May 18 13:10:14 rivoplus volumio[3186]: info: CorePlayQueue::getTrack 79 May 18 13:10:14 rivoplus volumio[3186]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:10:14 rivoplus volumio[3186]: verbose: CURRENT POSITION 79 May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::syncState stateService stop May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::syncState currentStatus stop May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioPushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output update for this device May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output May 18 13:10:14 rivoplus volumio[3186]: info: No code May 18 13:10:14 rivoplus volumio[3186]: info: CoreStateMachine::pushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioPushState May 18 13:10:14 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output update for this device May 18 13:10:14 rivoplus volumio[3186]: info: MRS: Pushing multiroomSync output May 18 13:10:14 rivoplus volumio[3186]: info: ------------------------------ 86ms May 18 13:10:14 rivoplus volumio[3186]: info: ------------------------------ 82ms May 18 13:10:14 rivoplus volumio[3186]: info: Signalling Playback active due to playback status change May 18 13:10:14 rivoplus volumio[3186]: info: Updating RAAT Signal Path May 18 13:10:14 rivoplus volumio[3186]: info: Updating RAAT Signal Path May 18 13:10:14 rivoplus volumio[3186]: info: Updating RAAT Signal Path May 18 13:10:14 rivoplus volumio[3186]: info: Updating RAAT Signal Path May 18 13:10:14 rivoplus volumio[3186]: info: Updating RAAT Signal Path May 18 13:10:14 rivoplus volumio[3186]: info: Updating RAAT Signal Path May 18 13:10:14 rivoplus volumio[3186]: info: MCU Signalled Playback Inactive May 18 13:10:15 rivoplus nmbd[3060]: [2025/05/18 13:10:15.713668, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 18 13:10:15 rivoplus nmbd[3060]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 18 13:10:16 rivoplus volumio[3186]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages May 18 13:10:16 rivoplus volumio[3186]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) May 18 13:10:16 rivoplus volumio[3186]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) May 18 13:10:16 rivoplus volumio[3186]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) May 18 13:10:16 rivoplus volumio[3186]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) May 18 13:10:16 rivoplus volumio[3186]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) May 18 13:10:17 rivoplus volumio[3186]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) May 18 13:10:17 rivoplus volumio[3186]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) May 18 13:10:17 rivoplus volumio[3186]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) May 18 13:10:17 rivoplus volumio[3186]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) May 18 13:10:17 rivoplus volumio[3186]: info: Building wheels for collected packages: camilladsp-plot May 18 13:10:17 rivoplus volumio[3186]: info: Running setup.py bdist_wheel for camilladsp-plot: started May 18 13:10:18 rivoplus volumio[3186]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' May 18 13:10:19 rivoplus volumio[3186]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-ewyefifo/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 May 18 13:10:19 rivoplus volumio[3186]: info: Successfully built camilladsp-plot May 18 13:10:19 rivoplus volumio[3186]: info: copying hw detection script May 18 13:10:19 rivoplus volumio[3186]: info: Detected cpu architecture as aarch64 May 18 13:10:21 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 13:10:21 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 13:10:21 rivoplus volumio[3186]: info: Discovery: Getting this device information May 18 13:10:21 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:21 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 13:10:26 rivoplus volumio[3186]: info: camilladsp May 18 13:10:26 rivoplus sudo[10767]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params May 18 13:10:26 rivoplus sudo[10767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:10:26 rivoplus sudo[10767]: pam_unix(sudo:session): session closed for user root May 18 13:10:26 rivoplus sudo[10769]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params May 18 13:10:26 rivoplus sudo[10769]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:10:26 rivoplus sudo[10769]: pam_unix(sudo:session): session closed for user root May 18 13:10:26 rivoplus sudo[10771]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 18 13:10:26 rivoplus sudo[10771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:10:27 rivoplus volumio[3186]: info: Reading package lists... May 18 13:10:27 rivoplus volumio[3186]: info: Building dependency tree... May 18 13:10:27 rivoplus volumio[3186]: info: Reading state information... May 18 13:10:29 rivoplus sudo[10771]: pam_unix(sudo:session): session closed for user root May 18 13:10:29 rivoplus volumio[3186]: info: drc is already the newest version (3.2.2~dfsg0-2). May 18 13:10:29 rivoplus sudo[10273]: pam_unix(sudo:session): session closed for user root May 18 13:10:29 rivoplus volumio[3186]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. May 18 13:10:29 rivoplus volumio[3186]: info: Install script completed May 18 13:10:29 rivoplus volumio[3186]: info: Done installing plugin. May 18 13:10:29 rivoplus volumio[3186]: info: Enabling plugin fusiondsp May 18 13:10:29 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 13:10:29 rivoplus volumio[3186]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false}] May 18 13:10:29 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 18 13:10:29 rivoplus volumio[3186]: info: Folder /tmp/plugins removed May 18 13:10:29 rivoplus volumio[3186]: info: Folder /tmp/downloaded_plugin.zip removed May 18 13:10:29 rivoplus volumio[3186]: Plugin install end detected on script May 18 13:10:29 rivoplus volumio[3186]: info: Folder /data/temp removed May 18 13:10:29 rivoplus volumio[3186]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 13:10:45 rivoplus volumio[3186]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 13:10:51 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 13:10:51 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 13:10:51 rivoplus volumio[3186]: info: Discovery: Getting this device information May 18 13:10:51 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:10:51 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 13:10:58 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 13:10:58 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 18 13:11:03 rivoplus volumio[3186]: info: Enabling plugin fusiondsp May 18 13:11:03 rivoplus volumio[3186]: info: Loading plugin "fusiondsp"... May 18 13:11:03 rivoplus volumio[3186]: info: Applying required configuration parameters for plugin fusiondsp May 18 13:11:03 rivoplus volumio[3186]: info: Preparing to generate the ALSA configuration file May 18 13:11:03 rivoplus volumio[3186]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 13:11:03 rivoplus volumio[3186]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 18 13:11:03 rivoplus volumio[3186]: info: Reading ALSA contributions from plugins. May 18 13:11:03 rivoplus volumio[3186]: info: Asound.conf file unchanged, so no further update is needed May 18 13:11:03 rivoplus volumio[3186]: info: Output device has changed, restarting MPD May 18 13:11:03 rivoplus sudo[10888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 13:11:03 rivoplus sudo[10888]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus sudo[10888]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus volumio[3186]: info: Output device has changed, restarting Shairport Sync May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 13:11:03 rivoplus sudo[10891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 13:11:03 rivoplus sudo[10891]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus systemd[1]: Stopping Music Player Daemon... May 18 13:11:03 rivoplus volumio[3186]: xcb_connection_has_error() returned true May 18 13:11:03 rivoplus volumio[3186]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 13:11:03 rivoplus volumio[3186]: xcb_connection_has_error() returned true May 18 13:11:03 rivoplus volumio[3186]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus systemd[1]: mpd.service: Succeeded. May 18 13:11:03 rivoplus systemd[1]: Stopped Music Player Daemon. May 18 13:11:03 rivoplus volumio[3186]: info: QobuzConnect: setDeactiveState invoked May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:11:03 rivoplus volumio[3186]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus vtcs[10182]: [2025-05-18 13:11:03.255] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 18 13:11:03 rivoplus vtcs[10182]: [2025-05-18 13:11:03.255] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 18 13:11:03 rivoplus systemd[1]: Starting Music Player Daemon... May 18 13:11:03 rivoplus volumio[3186]: info: PLUGIN START: fusiondsp May 18 13:11:03 rivoplus volumio[3186]: info: Loading i18n strings for locale en May 18 13:11:03 rivoplus sudo[10906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus sudo[10906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus volumio[3186]: info: FusionDsp - mixtype--------------------- None May 18 13:11:03 rivoplus volumio[3186]: info: Preparing to generate the ALSA configuration file May 18 13:11:03 rivoplus volumio[3186]: info: Done. May 18 13:11:03 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 18 13:11:03 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 18 13:11:03 rivoplus systemd[1]: vtcs.service: Succeeded. May 18 13:11:03 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 18 13:11:03 rivoplus sudo[10906]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus sudo[10911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 13:11:03 rivoplus sudo[10911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus sudo[10898]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 13:11:03 rivoplus sudo[10898]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus sudo[10898]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus sudo[10911]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus volumio[3186]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 13:11:03 rivoplus volumio[3186]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 18 13:11:03 rivoplus volumio[3186]: info: Reading ALSA contributions from plugins. May 18 13:11:03 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:03 rivoplus sudo[10926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 13:11:03 rivoplus sudo[10926]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 13:11:03 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:03 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:03 rivoplus volumio[3186]: info: MPD Permissions set May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus sudo[10926]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus sudo[10939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 13:11:03 rivoplus sudo[10939]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 13:11:03 rivoplus qobuz-connect[10101]: 20250518 13:11:03.567 [10101.10101] INFO SampleApp: Stopping Local configuration server May 18 13:11:03 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 18 13:11:03 rivoplus qobuz-connect[10101]: 20250518 13:11:03.578 [10101.10101] INFO SampleApp: shat down connection on UNIX socket May 18 13:11:03 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 18 13:11:03 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 18 13:11:03 rivoplus sudo[10939]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:03 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 13:11:03 rivoplus volumio[3186]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 18 13:11:03 rivoplus volumio[3186]: info: QobuzConnect: setDeactiveState invoked May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:11:03 rivoplus volumio[3186]: info: Starting Shairport Sync May 18 13:11:03 rivoplus volumio[3186]: info: Asound.conf file unchanged, so no further update is needed May 18 13:11:03 rivoplus volumio[3186]: info: Output device has changed, restarting MPD May 18 13:11:03 rivoplus sudo[10953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 13:11:03 rivoplus sudo[10953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 13:11:03 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 18 13:11:03 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 13:11:03 rivoplus sudo[10957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 13:11:03 rivoplus sudo[10957]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus volumio[3186]: info: Output device has changed, restarting Shairport Sync May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 13:11:03 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 13:11:03 rivoplus sudo[10953]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus sudo[10957]: pam_unix(sudo:session): session closed for user root May 18 13:11:03 rivoplus sudo[10961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 13:11:03 rivoplus sudo[10961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:03 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 18 13:11:03 rivoplus systemd[1]: mpd.service: Succeeded. May 18 13:11:03 rivoplus systemd[1]: Stopped Music Player Daemon. May 18 13:11:03 rivoplus systemd[1]: Starting Music Player Daemon... May 18 13:11:03 rivoplus volumio[3186]: xcb_connection_has_error() returned true May 18 13:11:03 rivoplus volumio[3186]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 13:11:03 rivoplus volumio[3186]: xcb_connection_has_error() returned true May 18 13:11:03 rivoplus volumio[3186]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus volumio[3186]: info: QobuzConnect: setDeactiveState invoked May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::volumioGetState May 18 13:11:03 rivoplus volumio[3186]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 18 13:11:03 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:03 rivoplus sudo[10975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 13:11:03 rivoplus sudo[10975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:04 rivoplus sudo[10975]: pam_unix(sudo:session): session closed for user root May 18 13:11:04 rivoplus sudo[10979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 13:11:04 rivoplus sudo[10979]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:04 rivoplus sudo[10969]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 13:11:04 rivoplus sudo[10969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:04 rivoplus sudo[10969]: pam_unix(sudo:session): session closed for user root May 18 13:11:04 rivoplus sudo[10979]: pam_unix(sudo:session): session closed for user root May 18 13:11:04 rivoplus sudo[10988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 13:11:04 rivoplus sudo[10988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: Not Reporting Auto name since its the default one May 18 13:11:04 rivoplus sudo[10988]: pam_unix(sudo:session): session closed for user root May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 13:11:04 rivoplus sudo[10995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 13:11:04 rivoplus sudo[10995]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:04 rivoplus volumio[3186]: info: MPD Permissions set May 18 13:11:04 rivoplus volumio[3186]: info: Shairport-Sync Started May 18 13:11:04 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 18 13:11:04 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT May 18 13:11:04 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 18 13:11:04 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 18 13:11:04 rivoplus volumio[3186]: info: Executing endpoint qc_getconfig May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 18 13:11:04 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 13:11:04 rivoplus volumio[3186]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus sudo[10995]: pam_unix(sudo:session): session closed for user root May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 18 13:11:04 rivoplus volumio[3186]: xcb_connection_has_error() returned true May 18 13:11:04 rivoplus sudo[11009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 18 13:11:04 rivoplus sudo[11009]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:04 rivoplus sudo[11009]: pam_unix(sudo:session): session closed for user root May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 13:11:04 rivoplus volumio[3186]: info: Not Reporting Auto name since its the default one May 18 13:11:04 rivoplus volumio[3186]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 13:11:04 rivoplus sudo[11015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 18 13:11:04 rivoplus sudo[11015]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 13:11:04 rivoplus volumio[3186]: TypeError: Cannot read property 'off' of undefined May 18 13:11:04 rivoplus volumio[3186]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 18 13:11:04 rivoplus volumio[3186]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 18 13:11:04 rivoplus volumio[3186]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 18 13:11:04 rivoplus volumio[3186]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 18 13:11:04 rivoplus volumio[3186]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 18 13:11:04 rivoplus volumio[3186]: at Socket.emit (events.js:400:28) May 18 13:11:04 rivoplus volumio[3186]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 18 13:11:04 rivoplus volumio[3186]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 18 13:11:04 rivoplus volumio[3186]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 13:11:04 rivoplus systemd[1]: Stopping RAAT DAEMON... May 18 13:11:04 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 18 13:11:04 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 18 13:11:04 rivoplus systemd[1]: Stopped RAAT DAEMON. May 18 13:11:04 rivoplus systemd[1]: Started RAAT DAEMON. May 18 13:11:04 rivoplus sudo[11015]: pam_unix(sudo:session): session closed for user root May 18 13:11:05 rivoplus sudo[11031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 13:10 May 18 13:11:05 rivoplus sudo[11031]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:04:38 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="0064c1a7ac4363e567dacab062cb5da8"