-- Logs begin at Thu 2025-04-24 09:48:13 UTC, end at Wed 2025-04-30 13:03:01 UTC. -- Apr 30 13:02:10 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:10Z" level=debug msg="fetched chunk 13/16, size: 524288" uri="spotify:track:0zPBxE08GriBhMNHNimzgy" Apr 30 13:02:13 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:13Z" level=trace msg="sent dealer ping" Apr 30 13:02:13 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:13Z" level=trace msg="received dealer pong" Apr 30 13:02:24 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:24Z" level=debug msg="fetched chunk 14/16, size: 524288" uri="spotify:track:0zPBxE08GriBhMNHNimzgy" Apr 30 13:02:32 motivo-gd volumio[940]: info: Enabling plugin fusiondsp Apr 30 13:02:32 motivo-gd volumio[940]: info: Loading plugin "fusiondsp"... Apr 30 13:02:32 motivo-gd volumio[940]: info: Preparing to generate the ALSA configuration file Apr 30 13:02:32 motivo-gd volumio[940]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 30 13:02:32 motivo-gd volumio[940]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 30 13:02:32 motivo-gd volumio[940]: info: Reading ALSA contributions from plugins. Apr 30 13:02:32 motivo-gd volumio[940]: info: Asound.conf file written Apr 30 13:02:32 motivo-gd sudo[8563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 30 13:02:32 motivo-gd sudo[8563]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:32 motivo-gd sudo[8563]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:32 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:32 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:32 motivo-gd volumio[940]: No state is present for card IQAudIODigi Apr 30 13:02:32 motivo-gd volumio[940]: Found hardware: "RPi-WM8804" "" "" "" "" Apr 30 13:02:32 motivo-gd volumio[940]: Hardware is initialized using a generic method Apr 30 13:02:32 motivo-gd volumio[940]: No state is present for card IQAudIODigi Apr 30 13:02:32 motivo-gd volumio[940]: No state is present for card Amanero Apr 30 13:02:32 motivo-gd volumio[940]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:0a23" "" "" Apr 30 13:02:32 motivo-gd volumio[940]: Hardware is initialized using a generic method Apr 30 13:02:32 motivo-gd volumio[940]: No state is present for card Amanero Apr 30 13:02:32 motivo-gd volumio[940]: info: Output device has changed, restarting MPD Apr 30 13:02:32 motivo-gd sudo[8568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 13:02:32 motivo-gd sudo[8568]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:32 motivo-gd volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 30 13:02:32 motivo-gd sudo[8568]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:32 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:32 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:32 motivo-gd sudo[8571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 13:02:32 motivo-gd sudo[8571]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:32 motivo-gd systemd[1]: Stopping Music Player Daemon... Apr 30 13:02:32 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:32 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:32 motivo-gd volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 13:02:32 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:32 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:32 motivo-gd volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 30 13:02:32 motivo-gd systemd[1]: mpd.service: Succeeded. Apr 30 13:02:32 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:32 motivo-gd systemd[1]: Stopped Music Player Daemon. Apr 30 13:02:32 motivo-gd volumio[940]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 30 13:02:32 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:32 motivo-gd vtcs[2732]: [2025-04-30 13:02:32.901] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Apr 30 13:02:32 motivo-gd vtcs[2732]: [2025-04-30 13:02:32.901] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 30 13:02:32 motivo-gd systemd[1]: Starting Music Player Daemon... Apr 30 13:02:32 motivo-gd volumio[940]: info: PLUGIN START: fusiondsp Apr 30 13:02:32 motivo-gd volumio[940]: info: Loading i18n strings for locale de Apr 30 13:02:32 motivo-gd sudo[8584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:32 motivo-gd sudo[8584]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:32 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 30 13:02:32 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:32 motivo-gd volumio[940]: info: FusionDsp - mixtype--------------------- Hardware Apr 30 13:02:32 motivo-gd volumio[940]: info: Preparing to generate the ALSA configuration file Apr 30 13:02:32 motivo-gd systemd[1]: Stopping Volumio Tidal Connect Service... Apr 30 13:02:32 motivo-gd volumio[940]: info: Done. Apr 30 13:02:32 motivo-gd systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:32 motivo-gd systemd[1]: vtcs.service: Succeeded. Apr 30 13:02:32 motivo-gd systemd[1]: Stopped Volumio Tidal Connect Service. Apr 30 13:02:32 motivo-gd sudo[8584]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:33 motivo-gd sudo[8576]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 30 13:02:33 motivo-gd sudo[8576]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:33 motivo-gd volumio[940]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 30 13:02:33 motivo-gd volumio[940]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 30 13:02:33 motivo-gd volumio[940]: info: Reading ALSA contributions from plugins. Apr 30 13:02:33 motivo-gd sudo[8576]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:33 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:33 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:33 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:33 motivo-gd sudo[8588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:33 motivo-gd sudo[8588]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:33 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 30 13:02:33 motivo-gd volumio[940]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 30 13:02:33 motivo-gd volumio[940]: info: MPD Permissions set Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd sudo[8588]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: Starting Shairport Sync Apr 30 13:02:33 motivo-gd sudo[8600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 13:02:33 motivo-gd sudo[8600]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:33 motivo-gd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 13:02:33 motivo-gd systemd[1]: shairport-sync.service: Succeeded. Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: Not Reporting Auto name since its the default one Apr 30 13:02:33 motivo-gd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:33 motivo-gd sudo[8600]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:33 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:33 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:33 motivo-gd volumio[940]: info: Asound.conf file unchanged, so no further update is needed Apr 30 13:02:33 motivo-gd volumio[940]: info: Output device has changed, restarting MPD Apr 30 13:02:33 motivo-gd volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:33 motivo-gd sudo[8610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 13:02:33 motivo-gd sudo[8610]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:33 motivo-gd sudo[8610]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:33 motivo-gd sudo[8612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 13:02:33 motivo-gd sudo[8612]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:33 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:33 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:33 motivo-gd volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 13:02:33 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:33 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:33 motivo-gd volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:33 motivo-gd systemd[1]: mpd.service: Succeeded. Apr 30 13:02:33 motivo-gd systemd[1]: Stopped Music Player Daemon. Apr 30 13:02:33 motivo-gd volumio[940]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 30 13:02:33 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:33 motivo-gd systemd[1]: Starting Music Player Daemon... Apr 30 13:02:33 motivo-gd volumio[940]: info: Preparing to generate the ALSA configuration file Apr 30 13:02:33 motivo-gd sudo[8622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:33 motivo-gd sudo[8622]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd volumio[940]: info: Shairport-Sync Started Apr 30 13:02:34 motivo-gd volumio[940]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 30 13:02:34 motivo-gd volumio[940]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 30 13:02:34 motivo-gd volumio[940]: info: Reading ALSA contributions from plugins. Apr 30 13:02:34 motivo-gd sudo[8622]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:34 motivo-gd sudo[8625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:34 motivo-gd sudo[8625]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8619]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 30 13:02:34 motivo-gd sudo[8619]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd volumio[940]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 30 13:02:34 motivo-gd sudo[8619]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: info: MPD Permissions set Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 30 13:02:34 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:34 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:34 motivo-gd sudo[8625]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd sudo[8634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 30 13:02:34 motivo-gd sudo[8634]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: Starting Shairport Sync Apr 30 13:02:34 motivo-gd sudo[8634]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: info: Asound.conf file written Apr 30 13:02:34 motivo-gd sudo[8641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 30 13:02:34 motivo-gd sudo[8641]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 13:02:34 motivo-gd sudo[8642]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 30 13:02:34 motivo-gd sudo[8645]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8645]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 13:02:34 motivo-gd systemd[1]: Stopping RAAT DAEMON... Apr 30 13:02:34 motivo-gd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:34 motivo-gd systemd[1]: raat-daemon.service: Succeeded. Apr 30 13:02:34 motivo-gd systemd[1]: Stopped RAAT DAEMON. Apr 30 13:02:34 motivo-gd systemd[1]: shairport-sync.service: Succeeded. Apr 30 13:02:34 motivo-gd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:34 motivo-gd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:34 motivo-gd systemd[1]: Started RAAT DAEMON. Apr 30 13:02:34 motivo-gd sudo[8641]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd sudo[8642]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:34 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:34 motivo-gd volumio[940]: No state is present for card IQAudIODigi Apr 30 13:02:34 motivo-gd volumio[940]: Found hardware: "RPi-WM8804" "" "" "" "" Apr 30 13:02:34 motivo-gd volumio[940]: Hardware is initialized using a generic method Apr 30 13:02:34 motivo-gd volumio[940]: No state is present for card IQAudIODigi Apr 30 13:02:34 motivo-gd volumio[940]: No state is present for card Amanero Apr 30 13:02:34 motivo-gd volumio[940]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:0a23" "" "" Apr 30 13:02:34 motivo-gd volumio[940]: Hardware is initialized using a generic method Apr 30 13:02:34 motivo-gd volumio[940]: No state is present for card Amanero Apr 30 13:02:34 motivo-gd volumio[940]: info: Output device has changed, restarting MPD Apr 30 13:02:34 motivo-gd volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:34 motivo-gd sudo[8665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 13:02:34 motivo-gd sudo[8665]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8665]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd sudo[8670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 13:02:34 motivo-gd sudo[8670]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:34 motivo-gd systemd[1]: mpd.service: Succeeded. Apr 30 13:02:34 motivo-gd systemd[1]: Stopped Music Player Daemon. Apr 30 13:02:34 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:34 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:34 motivo-gd volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 13:02:34 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:34 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:34 motivo-gd volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd systemd[1]: Starting Music Player Daemon... Apr 30 13:02:34 motivo-gd sudo[8684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:34 motivo-gd sudo[8684]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8684]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd sudo[8680]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 30 13:02:34 motivo-gd sudo[8680]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:34 motivo-gd sudo[8687]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8680]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: Not Reporting Auto name since its the default one Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:34 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:34 motivo-gd volumio[940]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 30 13:02:34 motivo-gd volumio[940]: info: MPD Permissions set Apr 30 13:02:34 motivo-gd volumio[940]: info: Shairport-Sync Started Apr 30 13:02:34 motivo-gd volumio[940]: info: Raat Daemon started successfully Apr 30 13:02:34 motivo-gd sudo[8687]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: info: Executing endpoint restartRAATSocket Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:34 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 30 13:02:34 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:34 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:34 motivo-gd sudo[8705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 30 13:02:34 motivo-gd sudo[8705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd sudo[8705]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:34 motivo-gd volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 30 13:02:34 motivo-gd volumio[940]: info: Starting Shairport Sync Apr 30 13:02:34 motivo-gd sudo[8707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 30 13:02:34 motivo-gd sudo[8707]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd systemd[1]: Stopping RAAT DAEMON... Apr 30 13:02:34 motivo-gd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:34 motivo-gd systemd[1]: raat-daemon.service: Succeeded. Apr 30 13:02:34 motivo-gd systemd[1]: Stopped RAAT DAEMON. Apr 30 13:02:34 motivo-gd sudo[8710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 13:02:34 motivo-gd sudo[8710]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:34 motivo-gd systemd[1]: Started RAAT DAEMON. Apr 30 13:02:34 motivo-gd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 13:02:34 motivo-gd sudo[8707]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd systemd[1]: shairport-sync.service: Succeeded. Apr 30 13:02:35 motivo-gd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:35 motivo-gd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:35 motivo-gd sudo[8710]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8730, instance 1, run: true Apr 30 13:02:35 motivo-gd volumio[940]: info: camilladsp service started and running in background, instance 1 Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:35 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:35 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:35 motivo-gd volumio[940]: cannot open device 'volumioHw': Device or resource busy Apr 30 13:02:35 motivo-gd volumio[940]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 30 13:02:35 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:35 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:35 motivo-gd volumio[940]: cannot open device 'volumioHw': Device or resource busy Apr 30 13:02:35 motivo-gd volumio[940]: info: FusionDsp loaded Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 13:02:35 motivo-gd sudo[8737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 30 13:02:35 motivo-gd sudo[8737]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:35 motivo-gd volumio[940]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 30 13:02:35 motivo-gd volumio[940]: info: Adding Signal Path Element [object Object] Apr 30 13:02:35 motivo-gd volumio[940]: info: Adding fusiondspeq DSP Signal Path Element Apr 30 13:02:35 motivo-gd volumio[940]: info: Additional DSP elements updated Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:35 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:35 motivo-gd volumio[940]: info: FusionDsp - ---- installed callbackRead Apr 30 13:02:35 motivo-gd volumio[940]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 30 13:02:35 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:35 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:35 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:35 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:35 motivo-gd volumio[940]: info: Shairport-Sync Started Apr 30 13:02:35 motivo-gd volumio[940]: info: Raat Daemon started successfully Apr 30 13:02:35 motivo-gd systemd[1]: Started FusionDsp Daemon. Apr 30 13:02:35 motivo-gd sudo[8737]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:02:35 motivo-gd volumio[940]: info: Not Reporting Auto name since its the default one Apr 30 13:02:35 motivo-gd volumio[940]: error: FusionDsp - WebSocket error: [object Object] Apr 30 13:02:35 motivo-gd volumio[940]: info: Executing endpoint restartRAATSocket Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 30 13:02:35 motivo-gd volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 30 13:02:35 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:35 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:35 motivo-gd volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 30 13:02:35 motivo-gd sudo[8748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 30 13:02:35 motivo-gd sudo[8748]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:35 motivo-gd sudo[8748]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd sudo[8750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 30 13:02:35 motivo-gd sudo[8750]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:35 motivo-gd mpd[8695]: Apr 30 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 30 13:02:35 motivo-gd systemd[1]: Started Music Player Daemon. Apr 30 13:02:35 motivo-gd sudo[8670]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd sudo[8612]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd sudo[8571]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd systemd[1]: Stopping RAAT DAEMON... Apr 30 13:02:35 motivo-gd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:35 motivo-gd systemd[1]: raat-daemon.service: Succeeded. Apr 30 13:02:35 motivo-gd systemd[1]: Stopped RAAT DAEMON. Apr 30 13:02:35 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:35 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:35 motivo-gd systemd[1]: Started RAAT DAEMON. Apr 30 13:02:35 motivo-gd sudo[8750]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:35 motivo-gd volumio[940]: error: MPD error: The expression evaluated to a falsy value: Apr 30 13:02:35 motivo-gd volumio[940]: assert.ok(self.idling) Apr 30 13:02:35 motivo-gd volumio[940]: error: The expression evaluated to a falsy value: Apr 30 13:02:35 motivo-gd volumio[940]: assert.ok(self.idling) Apr 30 13:02:35 motivo-gd volumio[940]: info: Raat Daemon started successfully Apr 30 13:02:35 motivo-gd volumio[940]: error: MPD error: The expression evaluated to a falsy value: Apr 30 13:02:35 motivo-gd volumio[940]: assert.ok(self.idling) Apr 30 13:02:35 motivo-gd volumio[940]: error: The expression evaluated to a falsy value: Apr 30 13:02:35 motivo-gd volumio[940]: assert.ok(self.idling) Apr 30 13:02:35 motivo-gd volumio[940]: error: updateQueue error: null Apr 30 13:02:35 motivo-gd volumio[940]: info: Executing endpoint restartRAATSocket Apr 30 13:02:35 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 30 13:02:35 motivo-gd volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 30 13:02:36 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:36 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:36 motivo-gd volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:36 motivo-gd volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:36 motivo-gd sudo[8781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 30 13:02:36 motivo-gd sudo[8781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:36 motivo-gd systemd[1]: Started Volumio Tidal Connect Service. Apr 30 13:02:36 motivo-gd sudo[8781]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:36 motivo-gd volumio[940]: 0+0 records in Apr 30 13:02:36 motivo-gd volumio[940]: 0+0 records out Apr 30 13:02:36 motivo-gd volumio[940]: 0 bytes copied, 0.000153498 s, 0.0 kB/s Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8796, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8803, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8811, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: Executing endpoint tc_getconfig Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 30 13:02:36 motivo-gd vtcs[8791]: STARTING TidalConnect services, version: 1.4.0.34 Apr 30 13:02:36 motivo-gd vtcs[8791]: STARTED TidalConnect services. Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8818, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: Executing endpoint tc_connect Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 30 13:02:36 motivo-gd volumio[940]: info: Connecting to TidalConnect Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::servicePushState Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:36 motivo-gd volumio[940]: info: CorePlayQueue::getTrack 0 Apr 30 13:02:36 motivo-gd volumio[940]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 30 13:02:36 motivo-gd volumio[940]: verbose: CURRENT POSITION 0 Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::syncState stateService stop Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::stPlaybackTimer Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::servicePushState Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:36 motivo-gd volumio[940]: info: CorePlayQueue::getTrack 0 Apr 30 13:02:36 motivo-gd volumio[940]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 30 13:02:36 motivo-gd volumio[940]: verbose: CURRENT POSITION 0 Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::syncState stateService stop Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:36 motivo-gd volumio[940]: info: No code Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:36 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:36 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:36 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:36 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:36 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:36 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:36 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:36 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:36 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:36 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8840, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8847, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: MCU Signalled Playback Inactive Apr 30 13:02:36 motivo-gd volumio[940]: info: MCU Signalled Playback Active Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8854, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8861, instance 1, run: true Apr 30 13:02:36 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8868, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8875, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8882, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8889, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:37 motivo-gd volumio[940]: info: RAAT: Requesting Headphone Status Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8904, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:37 motivo-gd volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:37 motivo-gd volumio[940]: info: MCU Signalled Headphone Mode Disabled Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 30 13:02:37 motivo-gd volumio[940]: info: Reporting Headphone State: false Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:37 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:37 motivo-gd sudo[8912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 30 13:02:37 motivo-gd sudo[8912]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8914, instance 1, run: true Apr 30 13:02:37 motivo-gd sudo[8912]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8922, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8929, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8936, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8943, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8950, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8957, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8964, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8971, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8978, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 8993, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:37 motivo-gd volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:37 motivo-gd sudo[9001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 30 13:02:37 motivo-gd sudo[9001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:37 motivo-gd sudo[9001]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9003, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: RAAT: Requesting Headphone Status Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9011, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: MCU Signalled Headphone Mode Disabled Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 30 13:02:37 motivo-gd volumio[940]: info: Reporting Headphone State: false Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:37 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:37 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9018, instance 1, run: true Apr 30 13:02:37 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9025, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9032, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9039, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9046, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9053, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9060, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9067, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9074, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9081, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9088, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9095, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9102, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: RAAT: Requesting Headphone Status Apr 30 13:02:38 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9109, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: MCU Signalled Headphone Mode Disabled Apr 30 13:02:38 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 30 13:02:38 motivo-gd volumio[940]: info: Reporting Headphone State: false Apr 30 13:02:38 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:38 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9116, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9123, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9130, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9137, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9144, instance 1, run: true Apr 30 13:02:38 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:38Z" level=debug msg="fetched chunk 15/16, size: 524288" uri="spotify:track:0zPBxE08GriBhMNHNimzgy" Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9151, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9158, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9165, instance 1, run: true Apr 30 13:02:38 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9172, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9179, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9186, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9193, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9200, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9207, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: TidalConnect service started! Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9214, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9221, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9228, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9235, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9242, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9249, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9256, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9263, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9270, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9277, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9284, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9291, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9298, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9305, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9312, instance 1, run: true Apr 30 13:02:39 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9319, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9326, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9333, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9340, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9347, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9354, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9361, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: TidalConnect service started! Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9368, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9375, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9382, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9389, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9396, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9403, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9410, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9417, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9424, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9431, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9438, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9445, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: TidalConnect service started! Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9452, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9459, instance 1, run: true Apr 30 13:02:40 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9466, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9473, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9480, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9487, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9494, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9501, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9508, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9515, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9522, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9529, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9536, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9543, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9550, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9557, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9564, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9571, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9578, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9585, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9592, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9599, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9606, instance 1, run: true Apr 30 13:02:41 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9613, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9620, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9627, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9634, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9641, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9648, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9655, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9662, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9669, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9676, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9683, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9690, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9697, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9704, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9711, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9718, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9725, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9732, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9739, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9746, instance 1, run: true Apr 30 13:02:42 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:42Z" level=trace msg="received accesspoint ping" Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9753, instance 1, run: true Apr 30 13:02:42 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:42Z" level=trace msg="received accesspoint pong ack" Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9760, instance 1, run: true Apr 30 13:02:42 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9767, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9774, instance 1, run: true Apr 30 13:02:43 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:43Z" level=trace msg="sent dealer ping" Apr 30 13:02:43 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:43Z" level=trace msg="received dealer pong" Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9781, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9788, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9795, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9802, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9809, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9816, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9823, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9830, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9837, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9844, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9851, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9858, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9865, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9872, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9879, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9886, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9893, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9900, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9907, instance 1, run: true Apr 30 13:02:43 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9914, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9921, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9928, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9935, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9942, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9949, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9956, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9963, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9970, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9977, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9984, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9991, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 9998, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10005, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10012, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10019, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10026, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10033, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10040, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10047, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10054, instance 1, run: true Apr 30 13:02:44 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10061, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10068, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10075, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10082, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10089, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10096, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10103, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10110, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10117, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10124, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10131, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10138, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10145, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10152, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10159, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10166, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10173, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10180, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10187, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10194, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10201, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10209, instance 1, run: true Apr 30 13:02:45 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10216, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10223, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10230, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10237, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10244, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10251, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10272, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10279, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10286, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10293, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10300, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10307, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10314, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10321, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10328, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10335, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10342, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10349, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10356, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10363, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10370, instance 1, run: true Apr 30 13:02:46 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10377, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10384, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10391, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10398, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10405, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10412, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10419, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10426, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10433, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10440, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10447, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10454, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10461, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10468, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10475, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10482, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10489, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10496, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10503, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10510, instance 1, run: true Apr 30 13:02:47 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10517, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10524, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10531, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10538, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10545, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10552, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10559, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10566, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10573, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10580, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10587, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10594, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10601, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10608, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10615, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10622, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10629, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10636, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10643, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10650, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10657, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10664, instance 1, run: true Apr 30 13:02:48 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10671, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10678, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10685, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10692, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10699, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10706, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10713, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10720, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10727, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10735, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10742, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10749, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10756, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10763, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10770, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10777, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10784, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10791, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10798, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10805, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10812, instance 1, run: true Apr 30 13:02:49 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10819, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10826, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10833, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10840, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10847, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10854, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10861, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10868, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10875, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10882, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10889, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10896, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10903, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10910, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10917, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10924, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10931, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10938, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10945, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10952, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10959, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10966, instance 1, run: true Apr 30 13:02:50 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10973, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10980, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10987, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 10994, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11001, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11008, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11015, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11022, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11029, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11036, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11043, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11050, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11057, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11064, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11071, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11078, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11085, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11092, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11099, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11106, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11113, instance 1, run: true Apr 30 13:02:51 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11120, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11127, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11134, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11141, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11148, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11155, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11162, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11169, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11176, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11183, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11190, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11197, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: Enabling plugin fusiondsp Apr 30 13:02:52 motivo-gd volumio[940]: info: Loading plugin "fusiondsp"... Apr 30 13:02:52 motivo-gd volumio[940]: info: Applying required configuration parameters for plugin fusiondsp Apr 30 13:02:52 motivo-gd volumio[940]: info: Preparing to generate the ALSA configuration file Apr 30 13:02:52 motivo-gd volumio[940]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 30 13:02:52 motivo-gd volumio[940]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 30 13:02:52 motivo-gd volumio[940]: info: Reading ALSA contributions from plugins. Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11204, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: Asound.conf file unchanged, so no further update is needed Apr 30 13:02:52 motivo-gd volumio[940]: info: Output device has changed, restarting MPD Apr 30 13:02:52 motivo-gd sudo[11212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 13:02:52 motivo-gd sudo[11212]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:52 motivo-gd volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:52 motivo-gd sudo[11212]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:52 motivo-gd sudo[11215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 13:02:52 motivo-gd sudo[11215]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:52 motivo-gd systemd[1]: Stopping Music Player Daemon... Apr 30 13:02:52 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:52 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:52 motivo-gd volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 13:02:52 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:52 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:52 motivo-gd volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:52 motivo-gd systemd[1]: mpd.service: Succeeded. Apr 30 13:02:52 motivo-gd systemd[1]: Stopped Music Player Daemon. Apr 30 13:02:52 motivo-gd volumio[940]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:52 motivo-gd vtcs[8791]: [2025-04-30 13:02:52.736] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Apr 30 13:02:52 motivo-gd vtcs[8791]: [2025-04-30 13:02:52.740] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 30 13:02:52 motivo-gd systemd[1]: Starting Music Player Daemon... Apr 30 13:02:52 motivo-gd volumio[940]: info: PLUGIN START: fusiondsp Apr 30 13:02:52 motivo-gd volumio[940]: info: Loading i18n strings for locale de Apr 30 13:02:52 motivo-gd sudo[11224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:52 motivo-gd sudo[11224]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:52 motivo-gd volumio[940]: info: FusionDsp - mixtype--------------------- Hardware Apr 30 13:02:52 motivo-gd volumio[940]: info: Preparing to generate the ALSA configuration file Apr 30 13:02:52 motivo-gd systemd[1]: Stopping Volumio Tidal Connect Service... Apr 30 13:02:52 motivo-gd systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:52 motivo-gd systemd[1]: vtcs.service: Succeeded. Apr 30 13:02:52 motivo-gd volumio[940]: info: Done. Apr 30 13:02:52 motivo-gd systemd[1]: Stopped Volumio Tidal Connect Service. Apr 30 13:02:52 motivo-gd sudo[11224]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:52 motivo-gd volumio[940]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 30 13:02:52 motivo-gd volumio[940]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 30 13:02:52 motivo-gd volumio[940]: info: Reading ALSA contributions from plugins. Apr 30 13:02:52 motivo-gd sudo[11220]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 30 13:02:52 motivo-gd sudo[11220]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:52 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:52 motivo-gd sudo[11220]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:52 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:52 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:52 motivo-gd sudo[11228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:52 motivo-gd sudo[11228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:52 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 30 13:02:52 motivo-gd volumio[940]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 30 13:02:52 motivo-gd volumio[940]: info: MPD Permissions set Apr 30 13:02:52 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11232, instance 1, run: true Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:52 motivo-gd sudo[11228]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:52 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11244, instance 1, run: true Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: Not Reporting Auto name since its the default one Apr 30 13:02:53 motivo-gd volumio[940]: info: Starting Shairport Sync Apr 30 13:02:53 motivo-gd volumio[940]: info: Asound.conf file unchanged, so no further update is needed Apr 30 13:02:53 motivo-gd volumio[940]: info: Output device has changed, restarting MPD Apr 30 13:02:53 motivo-gd sudo[11256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 13:02:53 motivo-gd sudo[11256]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:53 motivo-gd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 13:02:53 motivo-gd systemd[1]: shairport-sync.service: Succeeded. Apr 30 13:02:53 motivo-gd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:53 motivo-gd sudo[11259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 13:02:53 motivo-gd sudo[11259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:53 motivo-gd sudo[11259]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:53 motivo-gd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:53 motivo-gd volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:53 motivo-gd sudo[11256]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:53 motivo-gd sudo[11263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 13:02:53 motivo-gd sudo[11263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:53 motivo-gd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:53 motivo-gd systemd[1]: mpd.service: Succeeded. Apr 30 13:02:53 motivo-gd systemd[1]: Stopped Music Player Daemon. Apr 30 13:02:53 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:53 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:53 motivo-gd volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 13:02:53 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:53 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:53 motivo-gd systemd[1]: Starting Music Player Daemon... Apr 30 13:02:53 motivo-gd volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd go-librespot[6146]: time="2025-04-30T13:02:53Z" level=debug msg="fetched chunk 16/16, size: 191694" uri="spotify:track:0zPBxE08GriBhMNHNimzgy" Apr 30 13:02:53 motivo-gd sudo[11274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:53 motivo-gd sudo[11274]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:53 motivo-gd sudo[11269]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 30 13:02:53 motivo-gd sudo[11269]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:53 motivo-gd sudo[11269]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:53 motivo-gd sudo[11278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 30 13:02:53 motivo-gd sudo[11278]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:53 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11280, instance 1, run: true Apr 30 13:02:53 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:53 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:53 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:53 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:53 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:53 motivo-gd volumio[940]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 30 13:02:53 motivo-gd volumio[940]: info: MPD Permissions set Apr 30 13:02:53 motivo-gd volumio[940]: info: Shairport-Sync Started Apr 30 13:02:53 motivo-gd volumio[940]: (node:940) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 30 13:02:53 motivo-gd volumio[940]: (node:940) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 30 13:02:53 motivo-gd volumio[940]: (node:940) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 30 13:02:53 motivo-gd volumio[940]: (node:940) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 30 13:02:53 motivo-gd volumio[940]: (node:940) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 30 13:02:53 motivo-gd sudo[11274]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:53 motivo-gd sudo[11278]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:53 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:53 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:53 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11294, instance 1, run: true Apr 30 13:02:53 motivo-gd sudo[11293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:53 motivo-gd sudo[11293]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:53 motivo-gd sudo[11293]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 13:02:53 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd sudo[11303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 30 13:02:54 motivo-gd sudo[11303]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11307, instance 1, run: true Apr 30 13:02:54 motivo-gd volumio[940]: info: Starting Shairport Sync Apr 30 13:02:54 motivo-gd systemd[1]: Stopping RAAT DAEMON... Apr 30 13:02:54 motivo-gd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:54 motivo-gd systemd[1]: raat-daemon.service: Succeeded. Apr 30 13:02:54 motivo-gd systemd[1]: Stopped RAAT DAEMON. Apr 30 13:02:54 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:54 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:54 motivo-gd systemd[1]: Started RAAT DAEMON. Apr 30 13:02:54 motivo-gd sudo[11317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 13:02:54 motivo-gd sudo[11317]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:54 motivo-gd sudo[11303]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11323, instance 1, run: true Apr 30 13:02:54 motivo-gd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 13:02:54 motivo-gd volumio[940]: info: Raat Daemon started successfully Apr 30 13:02:54 motivo-gd systemd[1]: shairport-sync.service: Succeeded. Apr 30 13:02:54 motivo-gd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: Not Reporting Auto name since its the default one Apr 30 13:02:54 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:54 motivo-gd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 13:02:54 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:54 motivo-gd sudo[11317]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:54 motivo-gd volumio[940]: info: Executing endpoint restartRAATSocket Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11341, instance 1, run: true Apr 30 13:02:54 motivo-gd volumio[940]: info: Shairport-Sync Started Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 30 13:02:54 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:54 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:54 motivo-gd volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 30 13:02:54 motivo-gd sudo[11354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 30 13:02:54 motivo-gd sudo[11354]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11356, instance 1, run: true Apr 30 13:02:54 motivo-gd sudo[11354]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:54 motivo-gd sudo[11360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 30 13:02:54 motivo-gd sudo[11360]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11365, instance 1, run: true Apr 30 13:02:54 motivo-gd systemd[1]: Stopping RAAT DAEMON... Apr 30 13:02:54 motivo-gd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 30 13:02:54 motivo-gd systemd[1]: raat-daemon.service: Succeeded. Apr 30 13:02:54 motivo-gd systemd[1]: Stopped RAAT DAEMON. Apr 30 13:02:54 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:02:54 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 30 13:02:54 motivo-gd systemd[1]: Started RAAT DAEMON. Apr 30 13:02:54 motivo-gd sudo[11360]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11377, instance 1, run: true Apr 30 13:02:54 motivo-gd volumio[940]: info: Raat Daemon started successfully Apr 30 13:02:54 motivo-gd volumio[940]: info: Executing endpoint restartRAATSocket Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11390, instance 1, run: true Apr 30 13:02:54 motivo-gd volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11397, instance 1, run: true Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11404, instance 1, run: true Apr 30 13:02:54 motivo-gd mpd[11279]: Apr 30 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 30 13:02:54 motivo-gd systemd[1]: Started Music Player Daemon. Apr 30 13:02:54 motivo-gd sudo[11215]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:54 motivo-gd sudo[11263]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:54 motivo-gd volumio[940]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 30 13:02:54 motivo-gd volumio[940]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 30 13:02:54 motivo-gd volumio[940]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11419, instance 2, run: true Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp service started and running in background, instance 2 Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:54 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:54 motivo-gd volumio[940]: cannot open device 'volumioHw': Device or resource busy Apr 30 13:02:54 motivo-gd volumio[940]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 30 13:02:54 motivo-gd volumio[940]: No protocol specified Apr 30 13:02:54 motivo-gd volumio[940]: xcb_connection_has_error() returned true Apr 30 13:02:54 motivo-gd volumio[940]: cannot open device 'volumioHw': Device or resource busy Apr 30 13:02:54 motivo-gd volumio[940]: info: FusionDsp loaded Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 13:02:54 motivo-gd volumio[940]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 30 13:02:54 motivo-gd volumio[940]: info: Adding Signal Path Element [object Object] Apr 30 13:02:54 motivo-gd volumio[940]: info: Updating fusiondspeq DSP Signal Path Element Apr 30 13:02:54 motivo-gd volumio[940]: info: Additional DSP elements updated Apr 30 13:02:54 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:54 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:54 motivo-gd volumio[940]: info: FusionDsp - ---- installed callbackRead Apr 30 13:02:54 motivo-gd volumio[940]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 30 13:02:54 motivo-gd volumio[940]: info: FusionDsp - ---- read samplerate, raw: Apr 30 13:02:54 motivo-gd volumio[940]: error: FusionDsp - invalid sample rate Apr 30 13:02:54 motivo-gd volumio[940]: info: FusionDsp - ---- read samplerate, raw: Apr 30 13:02:54 motivo-gd volumio[940]: error: FusionDsp - invalid sample rate Apr 30 13:02:54 motivo-gd sudo[11429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 30 13:02:54 motivo-gd sudo[11429]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:54 motivo-gd sudo[11429]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:54 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11431, instance 2, run: true Apr 30 13:02:54 motivo-gd volumio[940]: error: FusionDsp - WebSocket error: [object Object] Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11438, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: error: MPD error: The expression evaluated to a falsy value: Apr 30 13:02:55 motivo-gd volumio[940]: assert.ok(self.idling) Apr 30 13:02:55 motivo-gd volumio[940]: error: The expression evaluated to a falsy value: Apr 30 13:02:55 motivo-gd volumio[940]: assert.ok(self.idling) Apr 30 13:02:55 motivo-gd volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 30 13:02:55 motivo-gd volumio[940]: error: updateQueue error: null Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11445, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11452, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11459, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11466, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11473, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11480, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11487, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11494, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11501, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11508, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11515, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11522, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11529, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11536, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11543, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11550, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11557, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11564, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11571, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11578, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11585, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11592, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11602, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11609, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11616, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11623, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11630, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11637, instance 1, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11644, instance 2, run: true Apr 30 13:02:55 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:55 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11655, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11666, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:56 motivo-gd sudo[11674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 30 13:02:56 motivo-gd sudo[11674]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11675, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11683, instance 2, run: true Apr 30 13:02:56 motivo-gd systemd[1]: Started Volumio Tidal Connect Service. Apr 30 13:02:56 motivo-gd sudo[11674]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11691, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11700, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11707, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11718, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11735, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: Executing endpoint tc_getconfig Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 30 13:02:56 motivo-gd vtcs[11684]: STARTING TidalConnect services, version: 1.4.0.34 Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11743, instance 2, run: true Apr 30 13:02:56 motivo-gd vtcs[11684]: STARTED TidalConnect services. Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11750, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: Executing endpoint tc_connect Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 30 13:02:56 motivo-gd volumio[940]: info: Connecting to TidalConnect Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11764, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::servicePushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:56 motivo-gd volumio[940]: info: CorePlayQueue::getTrack 0 Apr 30 13:02:56 motivo-gd volumio[940]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 30 13:02:56 motivo-gd volumio[940]: verbose: CURRENT POSITION 0 Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::syncState stateService stop Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:56 motivo-gd volumio[940]: info: No code Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::servicePushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:56 motivo-gd volumio[940]: info: CorePlayQueue::getTrack 0 Apr 30 13:02:56 motivo-gd volumio[940]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 30 13:02:56 motivo-gd volumio[940]: verbose: CURRENT POSITION 0 Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::syncState stateService stop Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:56 motivo-gd volumio[940]: info: No code Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreStateMachine::pushState Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::volumioPushState Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 30 13:02:56 motivo-gd volumio[940]: info: MRS: Pushing multiroomSync output Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11779, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:56 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:56 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:56 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:56 motivo-gd volumio[940]: info: Signalling Playback active due to playback status change Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11786, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11793, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11800, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11807, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11814, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11821, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11828, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11835, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11842, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: TidalConnect service stoped! Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11853, instance 1, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: RAAT: Requesting Headphone Status Apr 30 13:02:56 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 30 13:02:56 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11864, instance 2, run: true Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:56 motivo-gd volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 30 13:02:56 motivo-gd sudo[11872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 30 13:02:56 motivo-gd sudo[11872]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11873, instance 1, run: true Apr 30 13:02:57 motivo-gd sudo[11872]: pam_unix(sudo:session): session closed for user root Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11881, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: MCU Signalled Headphone Mode Disabled Apr 30 13:02:57 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 30 13:02:57 motivo-gd volumio[940]: info: Reporting Headphone State: false Apr 30 13:02:57 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:57 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11889, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11896, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11903, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11910, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11917, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11924, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11931, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11938, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11945, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11952, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11959, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11966, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: RAAT: Requesting Headphone Status Apr 30 13:02:57 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11973, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: MCU Signalled Headphone Mode Disabled Apr 30 13:02:57 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 30 13:02:57 motivo-gd volumio[940]: info: Reporting Headphone State: false Apr 30 13:02:57 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:57 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:02:57 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11980, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/motivo/buster/armhf Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11989, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 11992, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12003, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12010, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12017, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12024, instance 2, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12031, instance 1, run: true Apr 30 13:02:57 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12038, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12045, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12052, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12059, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12066, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12073, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12080, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12087, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12094, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12101, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12108, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12115, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12122, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12129, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12136, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12143, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12150, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12157, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12164, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12171, instance 1, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12178, instance 2, run: true Apr 30 13:02:58 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12185, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12192, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12199, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12206, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12213, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12220, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: TidalConnect service started! Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12227, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12234, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12241, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12248, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12255, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12262, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12269, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12276, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12283, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12290, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12297, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12304, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12311, instance 1, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12318, instance 2, run: true Apr 30 13:02:59 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12325, instance 1, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12332, instance 2, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: TidalConnect service started! Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12339, instance 1, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12346, instance 2, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/motivo/buster/armhf Apr 30 13:03:00 motivo-gd volumio[940]: info: FusionDsp - Stopping FusionDsp service Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp stopping service pid 12346... Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp service terminated, instance 2 Apr 30 13:03:00 motivo-gd sudo[12356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Apr 30 13:03:00 motivo-gd sudo[12356]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:03:00 motivo-gd systemd[1]: Stopping FusionDsp Daemon... Apr 30 13:03:00 motivo-gd volumio[8739]: Backend configuration: Apr 30 13:03:00 motivo-gd volumio[8739]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 30 13:03:00 motivo-gd volumio[8739]: active_config_txt: null Apr 30 13:03:00 motivo-gd volumio[8739]: camilla_host: 127.0.0.1 Apr 30 13:03:00 motivo-gd volumio[8739]: camilla_port: 9876 Apr 30 13:03:00 motivo-gd volumio[8739]: coeff_dir: /data/INTERNAL/FusionDsp/filters Apr 30 13:03:00 motivo-gd volumio[8739]: config_dir: /data/configuration/audio_interface/fusiondsp Apr 30 13:03:00 motivo-gd volumio[8739]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 30 13:03:00 motivo-gd volumio[8739]: log_file: /tmp/camilladsp.log Apr 30 13:03:00 motivo-gd volumio[8739]: on_get_active_config: null Apr 30 13:03:00 motivo-gd volumio[8739]: on_set_active_config: null Apr 30 13:03:00 motivo-gd volumio[8739]: port: 5011 Apr 30 13:03:00 motivo-gd volumio[8739]: supported_capture_types: null Apr 30 13:03:00 motivo-gd volumio[8739]: supported_playback_types: null Apr 30 13:03:00 motivo-gd volumio[8739]: update_config_symlink: false Apr 30 13:03:00 motivo-gd volumio[8739]: update_config_txt: false Apr 30 13:03:00 motivo-gd volumio[8739]: ======== Running on http://0.0.0.0:5011 ======== Apr 30 13:03:00 motivo-gd volumio[8739]: (Press CTRL+C to quit) Apr 30 13:03:00 motivo-gd volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12360, instance 1, run: true Apr 30 13:03:00 motivo-gd systemd[1]: fusiondsp.service: Succeeded. Apr 30 13:03:00 motivo-gd systemd[1]: Stopped FusionDsp Daemon. Apr 30 13:03:00 motivo-gd sudo[12356]: pam_unix(sudo:session): session closed for user root Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12367, instance 1, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: FusionDsp - Reporting Fusion DSP Disabled Apr 30 13:03:00 motivo-gd volumio[940]: info: Removing fusiondspeq DSP Signal Path Element Apr 30 13:03:00 motivo-gd volumio[940]: info: Additional DSP elements updated Apr 30 13:03:00 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:03:00 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 13:03:00 motivo-gd volumio[940]: info: Updating RAAT Signal Path Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12374, instance 1, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12381, instance 1, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12388, instance 1, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12395, instance 1, run: true Apr 30 13:03:00 motivo-gd volumio[940]: info: Check plugin dependencies Apr 30 13:03:00 motivo-gd volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 13:03:00 motivo-gd volumio[940]: info: Rename folder Apr 30 13:03:00 motivo-gd volumio[940]: info: Folder /tmp/downloaded_plugin.zip removed Apr 30 13:03:00 motivo-gd volumio[940]: info: Move to category Apr 30 13:03:00 motivo-gd volumio[940]: info: camilladsp spawned new process with pid 12406, instance 1, run: true Apr 30 13:03:01 motivo-gd volumio[940]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 30 13:03:01 motivo-gd volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 13:03:01 motivo-gd volumio[940]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 30 13:03:01 motivo-gd volumio[940]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 30 13:03:01 motivo-gd volumio[940]: at onErrorNT (internal/child_process.js:465:16) Apr 30 13:03:01 motivo-gd volumio[940]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 30 13:03:01 motivo-gd volumio[940]: errno: -2, Apr 30 13:03:01 motivo-gd volumio[940]: code: 'ENOENT', Apr 30 13:03:01 motivo-gd volumio[940]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 30 13:03:01 motivo-gd volumio[940]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 30 13:03:01 motivo-gd volumio[940]: spawnargs: [ Apr 30 13:03:01 motivo-gd volumio[940]: '-p', Apr 30 13:03:01 motivo-gd volumio[940]: 9876, Apr 30 13:03:01 motivo-gd volumio[940]: '-o', Apr 30 13:03:01 motivo-gd volumio[940]: '/tmp/camilladsp.log', Apr 30 13:03:01 motivo-gd volumio[940]: '-l', Apr 30 13:03:01 motivo-gd volumio[940]: 'warn', Apr 30 13:03:01 motivo-gd volumio[940]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 30 13:03:01 motivo-gd volumio[940]: ] Apr 30 13:03:01 motivo-gd volumio[940]: } Apr 30 13:03:01 motivo-gd volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 13:03:01 motivo-gd sudo[12423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 13:02 Apr 30 13:03:01 motivo-gd sudo[12423]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 11:38:59 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="98647f83a3fd242fcf8929c801e30fa6"