-- Logs begin at Sat 2025-09-13 15:29:03 EEST, end at Sat 2025-09-13 15:33:15 EEST. -- Sep 13 15:32:00 rivoplus volumio[3146]: 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 Sep 13 15:32:00 rivoplus volumio[3146]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Sep 13 15:32:00 rivoplus volumio[3146]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Sep 13 15:32:00 rivoplus volumio[3146]: 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) Sep 13 15:32:00 rivoplus volumio[3146]: 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) Sep 13 15:32:00 rivoplus volumio[3146]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Sep 13 15:32:00 rivoplus volumio[3146]: 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) Sep 13 15:32:00 rivoplus volumio[3146]: 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) Sep 13 15:32:00 rivoplus volumio[3146]: 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) Sep 13 15:32:00 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:00 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:00 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:00 rivoplus volumio[3146]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Sep 13 15:32:01 rivoplus volumio[3146]: info: Building wheels for collected packages: camilladsp-plot Sep 13 15:32:01 rivoplus volumio[3146]: info: Running setup.py bdist_wheel for camilladsp-plot: started Sep 13 15:32:02 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:02 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:02 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:02 rivoplus volumio[3146]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Sep 13 15:32:02 rivoplus volumio[3146]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-u5m6wf8d/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Sep 13 15:32:02 rivoplus volumio[3146]: info: Successfully built camilladsp-plot Sep 13 15:32:03 rivoplus volumio[3146]: info: copying hw detection script Sep 13 15:32:03 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:03 rivoplus volumio[3146]: info: Detected cpu architecture as aarch64 Sep 13 15:32:03 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:03 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:04 rivoplus volumio[3146]: info: camilladsp Sep 13 15:32:04 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:04 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:04 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:04 rivoplus sudo[4629]: 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 Sep 13 15:32:04 rivoplus sudo[4629]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:04 rivoplus sudo[4629]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:04 rivoplus sudo[4631]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Sep 13 15:32:04 rivoplus sudo[4631]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:04 rivoplus sudo[4631]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:04 rivoplus sudo[4633]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Sep 13 15:32:04 rivoplus sudo[4633]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:04 rivoplus volumio[3146]: info: Reading package lists... Sep 13 15:32:05 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:05 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:05 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:05 rivoplus volumio[3146]: info: Building dependency tree... Sep 13 15:32:05 rivoplus volumio[3146]: info: Reading state information... Sep 13 15:32:06 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:06 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:06 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:07 rivoplus volumio[3146]: info: drc is already the newest version (3.2.2~dfsg0-2). Sep 13 15:32:07 rivoplus sudo[4633]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:07 rivoplus volumio[3146]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Sep 13 15:32:07 rivoplus sudo[4197]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:07 rivoplus volumio[3146]: info: Install script completed Sep 13 15:32:07 rivoplus volumio[3146]: info: Done installing plugin. Sep 13 15:32:07 rivoplus volumio[3146]: info: Enabling plugin fusiondsp Sep 13 15:32:07 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 13 15:32:07 rivoplus volumio[3146]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false}] Sep 13 15:32:07 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 13 15:32:07 rivoplus volumio[3146]: info: Folder /tmp/plugins removed Sep 13 15:32:07 rivoplus volumio[3146]: info: Folder /tmp/downloaded_plugin.zip removed Sep 13 15:32:07 rivoplus volumio[3146]: Plugin install end detected on script Sep 13 15:32:07 rivoplus volumio[3146]: info: Folder /data/temp removed Sep 13 15:32:08 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:08 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:08 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:09 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:09 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:09 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:10 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:10 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:10 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:12 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:12 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:12 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:12 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:12 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:12 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:14 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:14 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:14 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:15 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:15 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:15 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:15 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:15 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:15 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:16 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:16 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:16 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:18 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:18 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:18 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:18 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:18 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:18 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:20 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:20 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:20 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:21 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:21 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:21 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:22 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:22 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:22 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:23 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:23 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:23 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:23 rivoplus volumio[3146]: info: Enabling plugin fusiondsp Sep 13 15:32:23 rivoplus volumio[3146]: info: Loading plugin "fusiondsp"... Sep 13 15:32:24 rivoplus volumio[3146]: info: Applying required configuration parameters for plugin fusiondsp Sep 13 15:32:24 rivoplus volumio[3146]: info: Preparing to generate the ALSA configuration file Sep 13 15:32:24 rivoplus volumio[3146]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 13 15:32:24 rivoplus volumio[3146]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 13 15:32:24 rivoplus volumio[3146]: info: Reading ALSA contributions from plugins. Sep 13 15:32:24 rivoplus volumio[3146]: info: Asound.conf file written Sep 13 15:32:24 rivoplus sudo[4676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 13 15:32:24 rivoplus sudo[4676]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:24 rivoplus sudo[4676]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:24 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:24 rivoplus volumio[3146]: No state is present for card AMLAUGESOUNDMP1 Sep 13 15:32:24 rivoplus volumio[3146]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Sep 13 15:32:24 rivoplus volumio[3146]: Hardware is initialized using a generic method Sep 13 15:32:24 rivoplus volumio[3146]: No state is present for card AMLAUGESOUNDMP1 Sep 13 15:32:24 rivoplus volumio[3146]: info: Output device has changed, restarting MPD Sep 13 15:32:24 rivoplus sudo[4681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 13 15:32:24 rivoplus sudo[4681]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:24 rivoplus sudo[4681]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:24 rivoplus volumio[3146]: info: Output device has changed, restarting Shairport Sync Sep 13 15:32:24 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:24 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 15:32:24 rivoplus sudo[4684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 13 15:32:24 rivoplus sudo[4684]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:24 rivoplus systemd[1]: Stopping Music Player Daemon... Sep 13 15:32:24 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:24 rivoplus volumio[3146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 13 15:32:24 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:24 rivoplus volumio[3146]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 13 15:32:24 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:24 rivoplus volumio[3146]: info: QobuzConnect: setDeactiveState invoked Sep 13 15:32:24 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:24 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:24 rivoplus volumio[3146]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Sep 13 15:32:24 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:24 rivoplus vtcs[3727]: [2025-09-13 15:32:24.857] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 13 15:32:24 rivoplus vtcs[3727]: [2025-09-13 15:32:24.857] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Sep 13 15:32:24 rivoplus vtcs[3727]: [2025-09-13 15:32:24.857] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 13 15:32:24 rivoplus volumio[3146]: info: PLUGIN START: fusiondsp Sep 13 15:32:24 rivoplus volumio[3146]: info: Loading i18n strings for locale en Sep 13 15:32:24 rivoplus sudo[4692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 13 15:32:24 rivoplus sudo[4692]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:24 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 13 15:32:24 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:24 rivoplus volumio[3146]: info: FusionDsp - mixtype--------------------- None Sep 13 15:32:24 rivoplus volumio[3146]: info: Preparing to generate the ALSA configuration file Sep 13 15:32:24 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Sep 13 15:32:24 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 13 15:32:24 rivoplus volumio[3146]: info: Done. Sep 13 15:32:24 rivoplus systemd[1]: vtcs.service: Succeeded. Sep 13 15:32:24 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Sep 13 15:32:24 rivoplus sudo[4692]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:24 rivoplus systemd[1]: mpd.service: Succeeded. Sep 13 15:32:24 rivoplus systemd[1]: Stopped Music Player Daemon. Sep 13 15:32:25 rivoplus sudo[4697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 13 15:32:25 rivoplus sudo[4697]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus systemd[1]: Starting Music Player Daemon... Sep 13 15:32:25 rivoplus sudo[4697]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus volumio[3146]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 13 15:32:25 rivoplus volumio[3146]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 13 15:32:25 rivoplus volumio[3146]: info: Reading ALSA contributions from plugins. Sep 13 15:32:25 rivoplus sudo[4709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 13 15:32:25 rivoplus sudo[4709]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:25 rivoplus sudo[4709]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:25 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 15:32:25 rivoplus sudo[4712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 13 15:32:25 rivoplus sudo[4712]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:25 rivoplus volumio[3146]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 13 15:32:25 rivoplus qobuz-connect[3602]: 20250913 15:32:25.171 [3602.3602] INFO SampleApp: Stopping Local configuration server Sep 13 15:32:25 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 13 15:32:25 rivoplus volumio[3146]: info: MPD Permissions set Sep 13 15:32:25 rivoplus qobuz-connect[3602]: 20250913 15:32:25.182 [3602.3602] INFO SampleApp: shat down connection on UNIX socket Sep 13 15:32:25 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Sep 13 15:32:25 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 13 15:32:25 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:25 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:25 rivoplus volumio[3146]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 13 15:32:25 rivoplus volumio[3146]: info: QobuzConnect: setDeactiveState invoked Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:25 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Sep 13 15:32:25 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus sudo[4712]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus sudo[4696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 13 15:32:25 rivoplus sudo[4696]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus sudo[4696]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 15:32:25 rivoplus volumio[3146]: info: Executing endpoint qc_getconfig Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.382 [4714.4714] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.388 [4714.4714] INFO VolumeManager: [0xabb767a0]: Setting new playback volume: 75 Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.388 [4714.4714] INFO VolumeManager: [0xabb767a0]: Setting new mute state: 0 Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.388 [4714.4714] INFO QobuzConnect: [0xabb77170]: Client initialized! Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.388 [4714.4714] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.402 [4714.4714] INFO LocalConfigManager: [0xabb76080]: Starting Local Configuration server Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.403 [4714.4714] INFO SampleApp: Starting Local configuration server Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.403 [4714.4714] INFO SampleApp: Connected to UNIX socket client 0xabb6bed8 Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: Not Reporting Auto name since its the default one Sep 13 15:32:25 rivoplus volumio[3146]: info: Starting Shairport Sync Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:25 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:25 rivoplus volumio[3146]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 13 15:32:25 rivoplus volumio[3146]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 13 15:32:25 rivoplus sudo[4731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 13 15:32:25 rivoplus sudo[4731]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus volumio[3146]: info: Asound.conf file unchanged, so no further update is needed Sep 13 15:32:25 rivoplus volumio[3146]: info: Output device has changed, restarting MPD Sep 13 15:32:25 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 13 15:32:25 rivoplus systemd[1]: shairport-sync.service: Succeeded. Sep 13 15:32:25 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.531 [4714.4714] INFO SampleApp: Playback volume changed: 75 Sep 13 15:32:25 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 13 15:32:25 rivoplus sudo[4731]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus sudo[4735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 13 15:32:25 rivoplus sudo[4735]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus volumio[3146]: info: Output device has changed, restarting Shairport Sync Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 15:32:25 rivoplus sudo[4735]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus sudo[4737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 13 15:32:25 rivoplus sudo[4737]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 13 15:32:25 rivoplus systemd[1]: mpd.service: Succeeded. Sep 13 15:32:25 rivoplus systemd[1]: Stopped Music Player Daemon. Sep 13 15:32:25 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:25 rivoplus volumio[3146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 13 15:32:25 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:25 rivoplus volumio[3146]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus systemd[1]: Starting Music Player Daemon... Sep 13 15:32:25 rivoplus volumio[3146]: info: QobuzConnect: setDeactiveState invoked Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:25 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:25 rivoplus volumio[3146]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:25 rivoplus sudo[4750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 13 15:32:25 rivoplus sudo[4750]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus sudo[4753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 13 15:32:25 rivoplus sudo[4753]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus sudo[4750]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus sudo[4753]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus sudo[4746]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 13 15:32:25 rivoplus sudo[4746]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus sudo[4746]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 13 15:32:25 rivoplus sudo[4763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 13 15:32:25 rivoplus sudo[4763]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus sudo[4763]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:25 rivoplus sudo[4768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 13 15:32:25 rivoplus sudo[4768]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:25 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:25 rivoplus volumio[3146]: info: Configured Audio card not found, not starting RAAT Sep 13 15:32:25 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 13 15:32:25 rivoplus qobuz-connect[4714]: 20250913 15:32:25.992 [4714.4714] INFO SampleApp: Stopping Local configuration server Sep 13 15:32:26 rivoplus qobuz-connect[4714]: 20250913 15:32:26.003 [4714.4714] INFO SampleApp: shat down connection on UNIX socket Sep 13 15:32:26 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:26 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 15:32:26 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 15:32:26 rivoplus sudo[4768]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:26 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:26 rivoplus volumio[3146]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 13 15:32:26 rivoplus volumio[3146]: info: MPD Permissions set Sep 13 15:32:26 rivoplus volumio[3146]: info: Shairport-Sync Started Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 15:32:26 rivoplus volumio[3146]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 13 15:32:26 rivoplus volumio[3146]: info: QobuzConnect: setDeactiveState invoked Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:26 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: Executing endpoint qc_getconfig Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.279 [4770.4770] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.286 [4770.4770] INFO VolumeManager: [0xabed07a0]: Setting new playback volume: 75 Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.286 [4770.4770] INFO VolumeManager: [0xabed07a0]: Setting new mute state: 0 Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.286 [4770.4770] INFO QobuzConnect: [0xabed1170]: Client initialized! Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.286 [4770.4770] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.301 [4770.4770] INFO LocalConfigManager: [0xabed0080]: Starting Local Configuration server Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.301 [4770.4770] INFO SampleApp: Starting Local configuration server Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.302 [4770.4770] INFO SampleApp: Connected to UNIX socket client 0xabec5ed8 Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 15:32:26 rivoplus volumio[3146]: info: Not Reporting Auto name since its the default one Sep 13 15:32:26 rivoplus volumio[3146]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 13 15:32:26 rivoplus volumio[3146]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 13 15:32:26 rivoplus volumio[3146]: info: Starting Shairport Sync Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 13 15:32:26 rivoplus sudo[4786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 13 15:32:26 rivoplus sudo[4786]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:26 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 13 15:32:26 rivoplus systemd[1]: shairport-sync.service: Succeeded. Sep 13 15:32:26 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 13 15:32:26 rivoplus qobuz-connect[4770]: 20250913 15:32:26.429 [4770.4770] INFO SampleApp: Playback volume changed: 75 Sep 13 15:32:26 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 13 15:32:26 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:26 rivoplus volumio[3146]: info: Configured Audio card not found, not starting RAAT Sep 13 15:32:26 rivoplus sudo[4786]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 15:32:26 rivoplus volumio[3146]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 15:32:26 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:26 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:26 rivoplus volumio[3146]: info: Shairport-Sync Started Sep 13 15:32:26 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4816, instance 1, run: true Sep 13 15:32:27 rivoplus volumio[3146]: info: camilladsp service started and running in background, instance 1 Sep 13 15:32:27 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:27 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:27 rivoplus volumio[3146]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Sep 13 15:32:27 rivoplus volumio[3146]: cannot open device 'volumioHw': No such device Sep 13 15:32:27 rivoplus volumio[3146]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 13 15:32:27 rivoplus volumio[3146]: xcb_connection_has_error() returned true Sep 13 15:32:27 rivoplus volumio[3146]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Sep 13 15:32:27 rivoplus volumio[3146]: cannot open device 'volumioHw': No such device Sep 13 15:32:27 rivoplus volumio[3146]: info: FusionDsp loaded Sep 13 15:32:27 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 15:32:27 rivoplus sudo[4823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 13 15:32:27 rivoplus sudo[4823]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:27 rivoplus volumio[3146]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 13 15:32:27 rivoplus volumio[3146]: info: Adding Signal Path Element [object Object] Sep 13 15:32:27 rivoplus volumio[3146]: info: Adding fusiondspeq DSP Signal Path Element Sep 13 15:32:27 rivoplus volumio[3146]: info: Additional DSP elements updated Sep 13 15:32:27 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:27 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:27 rivoplus volumio[3146]: info: Updating RAAT Signal Path Sep 13 15:32:27 rivoplus volumio[3146]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 13 15:32:27 rivoplus volumio[3146]: info: FusionDsp - ---- installed callbackRead Sep 13 15:32:27 rivoplus volumio[3146]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 13 15:32:27 rivoplus systemd[1]: Started FusionDsp Daemon. Sep 13 15:32:27 rivoplus sudo[4823]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:27 rivoplus volumio[3146]: error: FusionDsp - WebSocket error: [object Object] Sep 13 15:32:27 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:27 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:27 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:27 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:27 rivoplus mpd[4764]: Sep 13 15:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 13 15:32:27 rivoplus volumio[3146]: info: TidalConnect service stoped! Sep 13 15:32:28 rivoplus volumio[3146]: info: TidalConnect service stoped! Sep 13 15:32:28 rivoplus volumio[3146]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 13 15:32:28 rivoplus volumio[3146]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 13 15:32:28 rivoplus sudo[4835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 13 15:32:28 rivoplus sudo[4835]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:28 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:28 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:28 rivoplus volumio[3146]: 0 bytes copied, 0.000218375 s, 0.0 kB/s Sep 13 15:32:28 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Sep 13 15:32:28 rivoplus sudo[4835]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:28 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4839, instance 1, run: true Sep 13 15:32:28 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:28 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:28 rivoplus volumio[3146]: info: Executing endpoint tc_getconfig Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 13 15:32:28 rivoplus vtcs[4840]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 13 15:32:28 rivoplus vtcs[4840]: STARTED TidalConnect services. Sep 13 15:32:28 rivoplus volumio[3146]: info: Executing endpoint tc_connect Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 13 15:32:28 rivoplus volumio[3146]: info: Connecting to TidalConnect Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::servicePushState Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:28 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:28 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:28 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:28 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:28 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:28 rivoplus volumio[3146]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::servicePushState Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:28 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:28 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:28 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:28 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:28 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:28 rivoplus volumio[3146]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Sep 13 15:32:28 rivoplus volumio[3146]: info: TidalConnect service stoped! Sep 13 15:32:28 rivoplus volumio[3146]: info: TidalConnect service stoped! Sep 13 15:32:28 rivoplus volumio[3146]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 13 15:32:28 rivoplus volumio[3146]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 13 15:32:28 rivoplus volumio[3146]: info: RAAT: Requesting Headphone Status Sep 13 15:32:28 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Sep 13 15:32:29 rivoplus sudo[4870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 13 15:32:29 rivoplus sudo[4870]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:29 rivoplus sudo[4870]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:29 rivoplus volumio[3146]: info: MCU Signalled Headphone Mode Disabled Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 13 15:32:29 rivoplus volumio[3146]: info: Reporting Headphone State: false Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:29 rivoplus volumio[3146]: info: Updating RAAT Signal Path Sep 13 15:32:29 rivoplus volumio[3146]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 13 15:32:29 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:29 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:29 rivoplus volumio[3146]: 0 bytes copied, 0.000205916 s, 0.0 kB/s Sep 13 15:32:29 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4875, instance 1, run: true Sep 13 15:32:29 rivoplus volumio[3146]: info: RAAT: Requesting Headphone Status Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Sep 13 15:32:29 rivoplus volumio[3146]: info: MCU Signalled Headphone Mode Disabled Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 13 15:32:29 rivoplus volumio[3146]: info: Reporting Headphone State: false Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 15:32:29 rivoplus volumio[3146]: info: Updating RAAT Signal Path Sep 13 15:32:29 rivoplus volumio[3146]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 13 15:32:29 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:29 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:29 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:29 rivoplus systemd[1]: Started Music Player Daemon. Sep 13 15:32:29 rivoplus sudo[4684]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:29 rivoplus sudo[4737]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:29 rivoplus volumio[3146]: error: MPD error: The expression evaluated to a falsy value: Sep 13 15:32:29 rivoplus volumio[3146]: assert.ok(self.idling) Sep 13 15:32:29 rivoplus volumio[3146]: error: The expression evaluated to a falsy value: Sep 13 15:32:29 rivoplus volumio[3146]: assert.ok(self.idling) Sep 13 15:32:29 rivoplus volumio[3146]: error: updateQueue error: null Sep 13 15:32:30 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:30 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:30 rivoplus volumio[3146]: 0 bytes copied, 0.00018 s, 0.0 kB/s Sep 13 15:32:30 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4882, instance 1, run: true Sep 13 15:32:31 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:31 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:31 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:31 rivoplus volumio[3146]: info: TidalConnect service started! Sep 13 15:32:31 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:31 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:31 rivoplus volumio[3146]: 0 bytes copied, 0.000182542 s, 0.0 kB/s Sep 13 15:32:31 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4887, instance 1, run: true Sep 13 15:32:32 rivoplus volumio[3146]: info: TidalConnect service started! Sep 13 15:32:32 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:32 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:32 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:32 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:32 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:32 rivoplus volumio[3146]: 0 bytes copied, 0.0001885 s, 0.0 kB/s Sep 13 15:32:32 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4892, instance 1, run: true Sep 13 15:32:33 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:33 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:33 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:33 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:33 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:33 rivoplus volumio[3146]: 0 bytes copied, 0.000190959 s, 0.0 kB/s Sep 13 15:32:33 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4897, instance 1, run: true Sep 13 15:32:34 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:34 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:34 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:34 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:34 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:34 rivoplus volumio[3146]: 0 bytes copied, 0.000188583 s, 0.0 kB/s Sep 13 15:32:34 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4902, instance 1, run: true Sep 13 15:32:35 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:35 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:35 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:35 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:35 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:35 rivoplus volumio[3146]: 0 bytes copied, 0.000188583 s, 0.0 kB/s Sep 13 15:32:35 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4907, instance 1, run: true Sep 13 15:32:36 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:36 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:36 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:36 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:36 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:36 rivoplus volumio[3146]: 0 bytes copied, 0.000336708 s, 0.0 kB/s Sep 13 15:32:36 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4928, instance 1, run: true Sep 13 15:32:37 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 13 15:32:37 rivoplus volumio[3146]: info: Playing Remote Device: http://192.168.0.139 Sep 13 15:32:37 rivoplus volumio[3146]: info: Sep 13 15:32:37 rivoplus volumio[3146]: ---------------------------- Client requests Volumio play Sep 13 15:32:37 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPlay Sep 13 15:32:37 rivoplus volumio[3146]: info: CoreStateMachine::play index undefined Sep 13 15:32:37 rivoplus volumio[3146]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 15:32:37 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:37 rivoplus volumio[3146]: info: CoreStateMachine::startPlaybackTimer Sep 13 15:32:37 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:37 rivoplus volumio[3146]: verbose: ControllerMpd::clearAddPlayTracks USB/DEC03070C03050CF/ROCK/Alco Frisbass/2015 - Alco Frisbass/01 - La Suspension Ethéréenne.flac Sep 13 15:32:37 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 15:32:37 rivoplus volumio[3146]: info: sendMpdCommand stop took 6 milliseconds Sep 13 15:32:37 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand clear Sep 13 15:32:37 rivoplus volumio[3146]: info: Done playing: Sep 13 15:32:37 rivoplus volumio[3146]: info: Sep 13 15:32:37 rivoplus volumio[3146]: ---------------------------- MPD announces system playlist update Sep 13 15:32:37 rivoplus volumio[3146]: info: Ignoring MPD Status Update Sep 13 15:32:37 rivoplus volumio[3146]: info: sendMpdCommand clear took 10 milliseconds Sep 13 15:32:37 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand add "USB/DEC03070C03050CF/ROCK/Alco Frisbass/2015 - Alco Frisbass/01 - La Suspension Ethéréenne.flac" Sep 13 15:32:37 rivoplus volumio[3146]: info: Sep 13 15:32:37 rivoplus volumio[3146]: ---------------------------- MPD announces system playlist update Sep 13 15:32:37 rivoplus volumio[3146]: info: Ignoring MPD Status Update Sep 13 15:32:37 rivoplus volumio[3146]: error: updateQueue error: null Sep 13 15:32:37 rivoplus volumio[3146]: info: Sep 13 15:32:37 rivoplus volumio[3146]: ---------------------------- MPD announces system playlist update Sep 13 15:32:37 rivoplus volumio[3146]: info: Ignoring MPD Status Update Sep 13 15:32:37 rivoplus volumio[3146]: info: ------------------------------ 11ms Sep 13 15:32:37 rivoplus volumio[3146]: info: sendMpdCommand add "USB/DEC03070C03050CF/ROCK/Alco Frisbass/2015 - Alco Frisbass/01 - La Suspension Ethéréenne.flac" took 9 milliseconds Sep 13 15:32:37 rivoplus volumio[3146]: info: ------------------------------ 7ms Sep 13 15:32:37 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand play Sep 13 15:32:37 rivoplus volumio[3146]: info: Sep 13 15:32:37 rivoplus volumio[3146]: ---------------------------- MPD announces system playlist update Sep 13 15:32:37 rivoplus volumio[3146]: info: Ignoring MPD Status Update Sep 13 15:32:37 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:37 rivoplus volumio[3146]: 0+0 records in Sep 13 15:32:37 rivoplus volumio[3146]: 0+0 records out Sep 13 15:32:37 rivoplus volumio[3146]: 0 bytes copied, 0.000359291 s, 0.0 kB/s Sep 13 15:32:37 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4940, instance 1, run: true Sep 13 15:32:37 rivoplus volumio[3146]: info: Sep 13 15:32:37 rivoplus volumio[3146]: ---------------------------- MPD announces state update: player Sep 13 15:32:37 rivoplus volumio[3146]: info: ------------------------------ 85ms Sep 13 15:32:37 rivoplus volumio[3146]: info: sendMpdCommand play took 82 milliseconds Sep 13 15:32:37 rivoplus volumio[3146]: info: ------------------------------ 82ms Sep 13 15:32:37 rivoplus volumio[3146]: info: ControllerMpd::getState Sep 13 15:32:37 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand status Sep 13 15:32:37 rivoplus volumio[3146]: info: Sep 13 15:32:37 rivoplus volumio[3146]: ---------------------------- MPD announces state update: player Sep 13 15:32:37 rivoplus volumio[3146]: info: ControllerMpd::getState Sep 13 15:32:37 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand status Sep 13 15:32:37 rivoplus volumio[3146]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 13 15:32:37 rivoplus volumio[3146]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 13 15:32:37 rivoplus volumio[3146]: info: camilladsp stopping service pid 4940... Sep 13 15:32:38 rivoplus volumio[3146]: info: camilladsp service terminated, instance 1 Sep 13 15:32:38 rivoplus volumio[3146]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 13 15:32:38 rivoplus volumio[3146]: info: camilladsp service started and running in background, instance 1 Sep 13 15:32:38 rivoplus volumio[3146]: info: Sep 13 15:32:38 rivoplus volumio[3146]: ---------------------------- MPD announces state update: player Sep 13 15:32:38 rivoplus volumio[3146]: info: ControllerMpd::getState Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand status Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand status took 53 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand status took 50 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseState Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseState Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 15:32:38 rivoplus volumio[3146]: info: Sep 13 15:32:38 rivoplus volumio[3146]: ---------------------------- MPD announces state update: player Sep 13 15:32:38 rivoplus volumio[3146]: info: ControllerMpd::getState Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand status Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand status took 12 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand status took 5 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseState Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseTrackInfo Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseTrackInfo Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseState Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 15:32:38 rivoplus volumio[3146]: info: ControllerMpd::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::servicePushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":630,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"La Suspension Ethéréenne","artist":"Alco Frisbass","album":"Alco Frisbass","uri":"USB/DEC03070C03050CF/ROCK/Alco Frisbass/2015 - Alco Frisbass/01 - La Suspension Ethéréenne.flac","trackType":"flac"} Sep 13 15:32:38 rivoplus volumio[3146]: verbose: CURRENT POSITION 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState stateService play Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState currentStatus stop Sep 13 15:32:38 rivoplus volumio[3146]: info: ControllerMpd::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::servicePushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":630,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"La Suspension Ethéréenne","artist":"Alco Frisbass","album":"Alco Frisbass","uri":"USB/DEC03070C03050CF/ROCK/Alco Frisbass/2015 - Alco Frisbass/01 - La Suspension Ethéréenne.flac","trackType":"flac"} Sep 13 15:32:38 rivoplus volumio[3146]: verbose: CURRENT POSITION 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState stateService play Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState currentStatus play Sep 13 15:32:38 rivoplus volumio[3146]: info: Received an update from plugin. extracting info from payload Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:38 rivoplus volumio[3146]: info: ------------------------------ 102ms Sep 13 15:32:38 rivoplus volumio[3146]: info: ------------------------------ 104ms Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand playlistinfo took 40 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseTrackInfo Sep 13 15:32:38 rivoplus volumio[3146]: verbose: ControllerMpd::parseTrackInfo Sep 13 15:32:38 rivoplus volumio[3146]: info: ControllerMpd::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::servicePushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":630,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"La Suspension Ethéréenne","artist":"Alco Frisbass","album":"Alco Frisbass","uri":"USB/DEC03070C03050CF/ROCK/Alco Frisbass/2015 - Alco Frisbass/01 - La Suspension Ethéréenne.flac","trackType":"flac"} Sep 13 15:32:38 rivoplus volumio[3146]: verbose: CURRENT POSITION 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState stateService play Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState currentStatus play Sep 13 15:32:38 rivoplus volumio[3146]: info: Received an update from plugin. extracting info from payload Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:38 rivoplus volumio[3146]: info: ControllerMpd::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::servicePushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":630,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"La Suspension Ethéréenne","artist":"Alco Frisbass","album":"Alco Frisbass","uri":"USB/DEC03070C03050CF/ROCK/Alco Frisbass/2015 - Alco Frisbass/01 - La Suspension Ethéréenne.flac","trackType":"flac"} Sep 13 15:32:38 rivoplus volumio[3146]: verbose: CURRENT POSITION 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState stateService play Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::syncState currentStatus play Sep 13 15:32:38 rivoplus volumio[3146]: info: Received an update from plugin. extracting info from payload Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreStateMachine::pushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioPushState Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output update for this device Sep 13 15:32:38 rivoplus volumio[3146]: info: MRS: Pushing multiroomSync output Sep 13 15:32:38 rivoplus volumio[3146]: info: ------------------------------ 111ms Sep 13 15:32:38 rivoplus volumio[3146]: info: ------------------------------ 104ms Sep 13 15:32:38 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:38 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:38 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:39 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:39 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:39 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:39 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000506458 s, 129 MB/s Sep 13 15:32:39 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4948, instance 1, run: true Sep 13 15:32:39 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:39 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:39 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:40 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:40 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:40 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:40 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000865459 s, 75.7 MB/s Sep 13 15:32:40 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4953, instance 1, run: true Sep 13 15:32:40 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:40 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:40 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:41 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:41 rivoplus volumio[3146]: 1+1 records in Sep 13 15:32:41 rivoplus volumio[3146]: 1+1 records out Sep 13 15:32:41 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000545667 s, 113 MB/s Sep 13 15:32:41 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4958, instance 1, run: true Sep 13 15:32:41 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:41 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:41 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:42 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:42 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:42 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:42 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000519042 s, 126 MB/s Sep 13 15:32:42 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4963, instance 1, run: true Sep 13 15:32:42 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:42 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:42 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:43 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:43 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:43 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:43 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000867458 s, 75.5 MB/s Sep 13 15:32:43 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4968, instance 1, run: true Sep 13 15:32:43 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:43 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:43 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:44 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:44 rivoplus volumio[3146]: 1+1 records in Sep 13 15:32:44 rivoplus volumio[3146]: 1+1 records out Sep 13 15:32:44 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000521375 s, 118 MB/s Sep 13 15:32:44 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4976, instance 1, run: true Sep 13 15:32:44 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:44 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:44 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:45 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:45 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:45 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:45 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000499833 s, 131 MB/s Sep 13 15:32:45 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4981, instance 1, run: true Sep 13 15:32:46 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:46 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:46 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:46 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:46 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:46 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:46 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000506959 s, 129 MB/s Sep 13 15:32:46 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 4986, instance 1, run: true Sep 13 15:32:47 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:47 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:47 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:47 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:47 rivoplus volumio[3146]: 1+1 records in Sep 13 15:32:47 rivoplus volumio[3146]: 1+1 records out Sep 13 15:32:47 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000510292 s, 121 MB/s Sep 13 15:32:47 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5008, instance 1, run: true Sep 13 15:32:48 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:48 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:48 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:48 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:48 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:48 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:48 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000527458 s, 124 MB/s Sep 13 15:32:48 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5013, instance 1, run: true Sep 13 15:32:49 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:49 rivoplus volumio[3146]: 1+1 records in Sep 13 15:32:49 rivoplus volumio[3146]: 1+1 records out Sep 13 15:32:49 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000542166 s, 114 MB/s Sep 13 15:32:49 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5018, instance 1, run: true Sep 13 15:32:49 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:49 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:49 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:50 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:50 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:50 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:50 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000565042 s, 116 MB/s Sep 13 15:32:50 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5023, instance 1, run: true Sep 13 15:32:51 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:51 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:51 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:51 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:51 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:51 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:51 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000631667 s, 104 MB/s Sep 13 15:32:52 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5028, instance 1, run: true Sep 13 15:32:52 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:52 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:52 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:53 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:53 rivoplus volumio[3146]: 1+1 records in Sep 13 15:32:53 rivoplus volumio[3146]: 1+1 records out Sep 13 15:32:53 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000497125 s, 124 MB/s Sep 13 15:32:53 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5033, instance 1, run: true Sep 13 15:32:53 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:53 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:53 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:54 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:54 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:54 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:54 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000841375 s, 77.9 MB/s Sep 13 15:32:54 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5038, instance 1, run: true Sep 13 15:32:54 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:54 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:54 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:55 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:55 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:55 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:55 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.0005205 s, 126 MB/s Sep 13 15:32:55 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5043, instance 1, run: true Sep 13 15:32:55 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:55 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 15:32:55 rivoplus volumio[3146]: info: Discovery: Getting this device information Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:55 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 15:32:55 rivoplus volumio[3146]: info: Discovery: Getting this device information Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:55 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 15:32:55 rivoplus volumio[3146]: verbose: New Socket.io Connection to 192.168.0.139:3000 from 192.168.0.67 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:55 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 13 15:32:55 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 13 15:32:56 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:56 rivoplus volumio[3146]: 1+1 records in Sep 13 15:32:56 rivoplus volumio[3146]: 1+1 records out Sep 13 15:32:56 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000503958 s, 122 MB/s Sep 13 15:32:56 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5048, instance 1, run: true Sep 13 15:32:56 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:56 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:56 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:57 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:57 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:57 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:57 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:57 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:57 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:57 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.00050025 s, 131 MB/s Sep 13 15:32:57 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5108, instance 1, run: true Sep 13 15:32:57 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:57 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:58 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:58 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:58 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:58 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:58 rivoplus volumio[3146]: 2+0 records in Sep 13 15:32:58 rivoplus volumio[3146]: 2+0 records out Sep 13 15:32:58 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000502791 s, 130 MB/s Sep 13 15:32:58 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5113, instance 1, run: true Sep 13 15:32:59 rivoplus sudo[5118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 13 15:32:59 rivoplus sudo[5118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:59 rivoplus sudo[5118]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:59 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 13 15:32:59 rivoplus sudo[5122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 13 15:32:59 rivoplus sudo[5122]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:32:59 rivoplus sudo[5122]: pam_unix(sudo:session): session closed for user root Sep 13 15:32:59 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:59 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:32:59 rivoplus volumio[3146]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:59 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:32:59 rivoplus volumio[3146]: 1+1 records in Sep 13 15:32:59 rivoplus volumio[3146]: 1+1 records out Sep 13 15:32:59 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000504875 s, 122 MB/s Sep 13 15:32:59 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5127, instance 1, run: true Sep 13 15:32:59 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:32:59 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:32:59 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:00 rivoplus sudo[5131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 13 15:33:00 rivoplus sudo[5131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:33:00 rivoplus sudo[5131]: pam_unix(sudo:session): session closed for user root Sep 13 15:33:00 rivoplus sudo[5135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 13 15:33:00 rivoplus sudo[5135]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 15:33:00 rivoplus sudo[5135]: pam_unix(sudo:session): session closed for user root Sep 13 15:33:00 rivoplus volumio[3146]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:33:00 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:00 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:00 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 15:33:00 rivoplus volumio[3146]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 15:33:00 rivoplus volumio[3146]: info: Listing playlists Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 13 15:33:00 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 13 15:33:00 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:00 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:00 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:00 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000515208 s, 127 MB/s Sep 13 15:33:00 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5140, instance 1, run: true Sep 13 15:33:01 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:01 rivoplus volumio[3146]: 1+1 records in Sep 13 15:33:01 rivoplus volumio[3146]: 1+1 records out Sep 13 15:33:01 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000500875 s, 123 MB/s Sep 13 15:33:01 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5145, instance 1, run: true Sep 13 15:33:01 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:01 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:01 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:02 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:02 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:02 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:02 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:02 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:02 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:02 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000534958 s, 123 MB/s Sep 13 15:33:02 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5150, instance 1, run: true Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 13 15:33:03 rivoplus volumio[3146]: info: Received Get System Info Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 15:33:03 rivoplus volumio[3146]: info: Discovery: Getting this device information Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:03 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 15:33:03 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:03 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:03 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:03 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:03 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:03 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000837333 s, 78.3 MB/s Sep 13 15:33:03 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5158, instance 1, run: true Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 13 15:33:03 rivoplus volumio[3146]: info: Received Get System Info Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 15:33:03 rivoplus volumio[3146]: info: Discovery: Getting this device information Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:03 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:03 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 15:33:04 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:04 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:04 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:04 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:04 rivoplus volumio[3146]: 1+1 records in Sep 13 15:33:04 rivoplus volumio[3146]: 1+1 records out Sep 13 15:33:04 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000495708 s, 124 MB/s Sep 13 15:33:04 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5163, instance 1, run: true Sep 13 15:33:05 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:05 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:05 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:06 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:06 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:06 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:06 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.00071325 s, 91.9 MB/s Sep 13 15:33:06 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5168, instance 1, run: true Sep 13 15:33:07 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:07 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:07 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:07 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:07 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:07 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:07 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000511166 s, 128 MB/s Sep 13 15:33:07 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5183, instance 1, run: true Sep 13 15:33:08 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:08 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:08 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:08 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:08 rivoplus volumio[3146]: 1+1 records in Sep 13 15:33:08 rivoplus volumio[3146]: 1+1 records out Sep 13 15:33:08 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000495583 s, 125 MB/s Sep 13 15:33:08 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5195, instance 1, run: true Sep 13 15:33:08 rivoplus volumio[3146]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Sep 13 15:33:08 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:08 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:08 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 15:33:08 rivoplus volumio[3146]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 13 15:33:08 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 15:33:08 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 15:33:08 rivoplus volumio[3146]: info: Listing playlists Sep 13 15:33:09 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:09 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:09 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:09 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:09 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:09 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:09 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000503041 s, 130 MB/s Sep 13 15:33:09 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5200, instance 1, run: true Sep 13 15:33:10 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:10 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:10 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:10 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:10 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000500625 s, 131 MB/s Sep 13 15:33:10 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5205, instance 1, run: true Sep 13 15:33:10 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:10 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:11 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:11 rivoplus volumio[3146]: 1+1 records in Sep 13 15:33:11 rivoplus volumio[3146]: 1+1 records out Sep 13 15:33:11 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.000498542 s, 124 MB/s Sep 13 15:33:11 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5210, instance 1, run: true Sep 13 15:33:11 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:11 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:11 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:12 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:12 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:12 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:12 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:12 rivoplus volumio[3146]: 2+0 records in Sep 13 15:33:12 rivoplus volumio[3146]: 2+0 records out Sep 13 15:33:12 rivoplus volumio[3146]: 65536 bytes (66 kB, 64 KiB) copied, 0.000498959 s, 131 MB/s Sep 13 15:33:12 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5215, instance 1, run: true Sep 13 15:33:13 rivoplus volumio[3146]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.67 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:13 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:13 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:13 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 15:33:13 rivoplus volumio[3146]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 13 15:33:13 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 15:33:13 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 15:33:13 rivoplus volumio[3146]: info: Listing playlists Sep 13 15:33:13 rivoplus volumio[3146]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 13 15:33:13 rivoplus volumio[3146]: 1+1 records in Sep 13 15:33:13 rivoplus volumio[3146]: 1+1 records out Sep 13 15:33:13 rivoplus volumio[3146]: 61712 bytes (62 kB, 60 KiB) copied, 0.00085725 s, 72.0 MB/s Sep 13 15:33:13 rivoplus volumio[3146]: info: camilladsp spawned new process with pid 5220, instance 1, run: true Sep 13 15:33:13 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:13 rivoplus volumio[3146]: info: CoreCommandRouter::volumioGetState Sep 13 15:33:13 rivoplus volumio[3146]: info: CorePlayQueue::getTrack 0 Sep 13 15:33:14 rivoplus volumio[3146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 13 15:33:14 rivoplus volumio[3146]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Sep 13 15:33:14 rivoplus volumio[3146]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Sep 13 15:33:14 rivoplus volumio[3146]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 15:33:14 rivoplus volumio[3146]: TypeError: Cannot read property 'then' of undefined Sep 13 15:33:14 rivoplus volumio[3146]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Sep 13 15:33:14 rivoplus volumio[3146]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Sep 13 15:33:14 rivoplus volumio[3146]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Sep 13 15:33:14 rivoplus volumio[3146]: at Socket.emit (events.js:400:28) Sep 13 15:33:14 rivoplus volumio[3146]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 13 15:33:14 rivoplus volumio[3146]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 13 15:33:14 rivoplus volumio[3146]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 15:33:15 rivoplus sudo[5232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 15:32 Sep 13 15:33:15 rivoplus sudo[5232]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"