-- Logs begin at Wed 2025-04-16 07:06:59 CEST, end at Fri 2025-04-18 05:54:20 CEST. -- Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "upnp_browser"... Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "alarm-clock"... Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "airplay_emulation"... Apr 18 05:53:00 volumio volumio[10531]: info: Starting Shairport Sync Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "last_100"... Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "webradio"... Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "i2s_dacs"... Apr 18 05:53:00 volumio volumio[10531]: info: I2S DAC not set, start Auto-detection Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "volumiodiscovery"... Apr 18 05:53:00 volumio volumio[10531]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:00 volumio volumio[10531]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:00 volumio volumio[10531]: *** WARNING *** For more information see Apr 18 05:53:00 volumio node[10531]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:00 volumio volumio[10531]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:00 volumio volumio[10531]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:00 volumio volumio[10531]: *** WARNING *** For more information see Apr 18 05:53:00 volumio node[10531]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:00 volumio node[10531]: *** WARNING *** For more information see Apr 18 05:53:00 volumio node[10531]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:00 volumio node[10531]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:00 volumio node[10531]: *** WARNING *** For more information see Apr 18 05:53:00 volumio volumio[10531]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 05:53:00 volumio volumio[10531]: info: Discovery: Started advertising with name: Volumio Apr 18 05:53:00 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:00 volumio volumio[10531]: info: Loading plugin "spop"... Apr 18 05:53:01 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 18 05:53:02 volumio volumio-remote-updater[634]: [2025-04-18 03:53:02] [connect] Successful connection Apr 18 05:53:02 volumio volumio[10531]: info: Loading plugin "outputs"... Apr 18 05:53:02 volumio volumio[10531]: info: Loading plugin "albumart"... Apr 18 05:53:02 volumio volumio[10531]: info: Plugin example_plugin is not enabled Apr 18 05:53:02 volumio volumio[10531]: info: Loading plugin "inputs"... Apr 18 05:53:02 volumio volumio[10531]: info: Loading plugin "updater_comm"... Apr 18 05:53:02 volumio volumio[10531]: info: Plugin mpdemulation is not enabled Apr 18 05:53:02 volumio volumio[10531]: info: Loading plugin "rest_api"... Apr 18 05:53:02 volumio volumio[10531]: info: Loading plugin "websocket"... Apr 18 05:53:02 volumio volumio[10531]: info: Starting Socket.io Server version 2.3.0 Apr 18 05:53:02 volumio volumio[10531]: info: Loading plugin "fusiondsp"... Apr 18 05:53:02 volumio volumio[10531]: Forking 3 albumart workers Apr 18 05:53:03 volumio volumio[10531]: info: Applying required configuration parameters for plugin fusiondsp Apr 18 05:53:03 volumio volumio[10531]: info: Loading i18n strings for locale en Apr 18 05:53:03 volumio volumio[10531]: Updating browse sources language Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::initPlayerControls Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:03 volumio sudo[10571]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:03 volumio volumio[10531]: Express server listening on port 3000 Apr 18 05:53:03 volumio volumio[10531]: [Metrics] WebUI: 5s 71.08ms Apr 18 05:53:03 volumio volumio[10531]: info: CoreStateMachine::resetVolumioState Apr 18 05:53:03 volumio volumio[10531]: info: CoreStateMachine::getcurrentVolume Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 05:53:03 volumio volumio[10531]: info: CoreStateMachine::pushState Apr 18 05:53:03 volumio volumio[10531]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:03 volumio volumio[10531]: info: Volumio Network Manager: Network status updated: 1 Apr 18 05:53:03 volumio volumio-remote-updater[634]: [2025-04-18 03:53:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744948382 101 Apr 18 05:53:03 volumio volumio[10531]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 18 05:53:03 volumio volumio[10531]: info: Reloading queue from file Apr 18 05:53:03 volumio volumio[10531]: info: CoreStateMachine::setRepeat null single undefined Apr 18 05:53:03 volumio volumio[10531]: info: CoreStateMachine::pushState Apr 18 05:53:03 volumio volumio[10531]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:03 volumio volumio[10531]: info: CoreStateMachine::setRandom null Apr 18 05:53:03 volumio volumio[10531]: info: CoreStateMachine::pushState Apr 18 05:53:03 volumio volumio[10531]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:03 volumio volumio[10531]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:03 volumio volumio[10531]: info: Setting Device type: Raspberry PI Apr 18 05:53:03 volumio volumio[10531]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 18 05:53:03 volumio volumio[10531]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Apr 18 05:53:03 volumio volumio[10531]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 18 05:53:03 volumio volumio[10531]: Starting albumart workers Apr 18 05:53:03 volumio volumio[10531]: info: Completed loading Core Plugins Apr 18 05:53:03 volumio volumio[10531]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:04 volumio volumio[10531]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:04 volumio volumio[10531]: info: Reading ALSA contributions from plugins. Apr 18 05:53:04 volumio volumio[10531]: info: Discovery: adding e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:04 volumio volumio[10531]: info: Discovery: Found device Volumio Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:04 volumio volumio[10531]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:04 volumio volumio[10531]: info: Discovery: this is already registered, e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:04 volumio volumio[10531]: info: Discovery: Found device Volumio Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:04 volumio volumio[10531]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:04 volumio volumio[10531]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:04 volumio volumio[10531]: info: Output device has changed, restarting MPD Apr 18 05:53:04 volumio volumio[10531]: Starting albumart workers Apr 18 05:53:04 volumio volumio[10531]: Starting albumart workers Apr 18 05:53:04 volumio volumio[10531]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:04 volumio sudo[10624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:04 volumio sudo[10624]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:04 volumio sudo[10624]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:04 volumio sudo[10626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:04 volumio sudo[10626]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:04 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 05:53:04 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:04 volumio volumio[10531]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:04 volumio volumio[10531]: info: ___________ START PLUGINS ___________ Apr 18 05:53:04 volumio volumio[10531]: info: ControllerMpd::onStart: Initializing MPD Apr 18 05:53:04 volumio volumio[10531]: info: Creating MPD Configuration file Apr 18 05:53:04 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:04 volumio volumio[10531]: info: [1744948384432] CoreMusicLibrary::Adding element Media Servers Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:04 volumio sudo[10634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:04 volumio sudo[10634]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:04 volumio sudo[10634]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:04 volumio sudo[10636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:04 volumio sudo[10632]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:04 volumio sudo[10632]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:04 volumio sudo[10636]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:04 volumio sudo[10632]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:04 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:04 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:04 volumio volumio[10531]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:04 volumio sudo[10644]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:04 volumio sudo[10644]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:04 volumio sudo[10644]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:04 volumio volumio[10531]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:04 volumio volumio[10531]: info: [1744948384872] CoreMusicLibrary::Adding element Last_100 Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:04 volumio volumio[10531]: info: [1744948384877] CoreMusicLibrary::Adding element Webradio Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:04 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:04 volumio volumio[10531]: info: Initializing BBC Radios Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: Creating Spotify config file Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: Loading i18n strings for locale en Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: FusionDsp - mixtype--------------------- None Apr 18 05:53:05 volumio volumio[10531]: info: Volumio Calling Home Apr 18 05:53:05 volumio volumio[10531]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:05 volumio volumio[10531]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:05 volumio volumio[10531]: info: Reading ALSA contributions from plugins. Apr 18 05:53:05 volumio volumio[10531]: info: MPD Permissions set Apr 18 05:53:05 volumio volumio[10531]: info: MPD Permissions set Apr 18 05:53:05 volumio volumio[10531]: info: Volumio called home Apr 18 05:53:05 volumio volumio[10531]: info: Spotify config file written Apr 18 05:53:05 volumio sudo[10688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 05:53:05 volumio sudo[10688]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:05 volumio systemd[1]: Stopping go-librespot Daemon... Apr 18 05:53:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:05 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 18 05:53:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 05:53:05 volumio volumio[10531]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 18 05:53:05 volumio systemd[1]: Started go-librespot Daemon. Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio go-librespot[10690]: go-librespot daemon starting... Apr 18 05:53:05 volumio sudo[10688]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:05 volumio go-librespot[10690]: time="2025-04-18T05:53:05+02:00" level=info msg="running go-librespot 0.2.0" Apr 18 05:53:05 volumio go-librespot[10690]: time="2025-04-18T05:53:05+02:00" level=debug msg="app state loaded" Apr 18 05:53:05 volumio go-librespot[10690]: time="2025-04-18T05:53:05+02:00" level=debug msg="stored credentials not found" Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:05 volumio go-librespot[10690]: time="2025-04-18T05:53:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:05 volumio volumio[10531]: info: No need to fix Spotify hosts Apr 18 05:53:06 volumio volumio[10531]: info: Starting Shairport Sync Apr 18 05:53:06 volumio go-librespot[10690]: time="2025-04-18T05:53:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 18 05:53:06 volumio go-librespot[10690]: time="2025-04-18T05:53:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 18 05:53:06 volumio go-librespot[10690]: time="2025-04-18T05:53:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 18 05:53:06 volumio volumio[10531]: info: Starting Shairport Sync Apr 18 05:53:06 volumio go-librespot[10690]: time="2025-04-18T05:53:06+02:00" level=info msg="zeroconf server listening on port 38003" Apr 18 05:53:06 volumio sudo[10708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:06 volumio volumio[10531]: info: Starting Shairport Sync Apr 18 05:53:06 volumio sudo[10708]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:06 volumio sudo[10710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:06 volumio sudo[10710]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:06 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:06 volumio sudo[10713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:06 volumio sudo[10713]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:06 volumio volumio[10531]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:06 volumio volumio[10531]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:06 volumio volumio[10531]: info: Output device has changed, restarting MPD Apr 18 05:53:06 volumio sudo[10708]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:06 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:06 volumio volumio[10531]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:06 volumio sudo[10719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:06 volumio volumio[10531]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:06 volumio sudo[10719]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:06 volumio sudo[10719]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:06 volumio sudo[10722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:06 volumio sudo[10722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:06 volumio sudo[10710]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:06 volumio sudo[10713]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:06 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:06 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:06 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:06 volumio volumio[10531]: info: MPD Permissions set Apr 18 05:53:06 volumio volumio[10531]: info: Shairport-Sync Started Apr 18 05:53:06 volumio volumio[10531]: Error adding Membership: Error: addMembership EINVAL Apr 18 05:53:06 volumio volumio[10531]: info: Shairport-Sync Started Apr 18 05:53:06 volumio volumio[10531]: info: Shairport-Sync Started Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:06 volumio volumio[10531]: info: Starting Shairport Sync Apr 18 05:53:06 volumio sudo[10739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:06 volumio sudo[10739]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:06 volumio sudo[10731]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:06 volumio sudo[10731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:06 volumio sudo[10731]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:06 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:06 volumio sudo[10739]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:06 volumio volumio[10531]: info: Shairport-Sync Started Apr 18 05:53:07 volumio volumio[10531]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:07 volumio volumio[10531]: 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 18 05:53:07 volumio volumio[10531]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:07 volumio volumio[10531]: info: camilladsp service started and running in background, instance 1 Apr 18 05:53:07 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:07 volumio volumio[10531]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:07 volumio volumio[10531]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 18 05:53:07 volumio volumio[10531]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:07 volumio volumio[10531]: info: FusionDsp loaded Apr 18 05:53:07 volumio volumio[10531]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:07 volumio sudo[10756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 18 05:53:07 volumio sudo[10756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:07 volumio volumio[10531]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 18 05:53:07 volumio volumio[10531]: info: Adding Signal Path Element [object Object] Apr 18 05:53:07 volumio volumio[10531]: info: Adding fusiondspeq DSP Signal Path Element Apr 18 05:53:07 volumio volumio[10531]: info: FusionDsp - ---- installed callbackRead Apr 18 05:53:07 volumio volumio[10531]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:07 volumio volumio[10531]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 18 05:53:07 volumio volumio[10531]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 18 05:53:07 volumio volumio[10531]: at onErrorNT (internal/child_process.js:465:16) Apr 18 05:53:07 volumio volumio[10531]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 18 05:53:07 volumio volumio[10531]: errno: -2, Apr 18 05:53:07 volumio volumio[10531]: code: 'ENOENT', Apr 18 05:53:07 volumio volumio[10531]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:07 volumio volumio[10531]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:07 volumio volumio[10531]: spawnargs: [ Apr 18 05:53:07 volumio volumio[10531]: '-p', Apr 18 05:53:07 volumio volumio[10531]: 9876, Apr 18 05:53:07 volumio volumio[10531]: '-o', Apr 18 05:53:07 volumio volumio[10531]: '/tmp/camilladsp.log', Apr 18 05:53:07 volumio volumio[10531]: '-l', Apr 18 05:53:07 volumio volumio[10531]: 'warn', Apr 18 05:53:07 volumio volumio[10531]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 18 05:53:07 volumio volumio[10531]: ] Apr 18 05:53:07 volumio volumio[10531]: } Apr 18 05:53:07 volumio volumio[10531]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:07 volumio sudo[10756]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:08 volumio sudo[10769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:52 Apr 18 05:53:08 volumio sudo[10769]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:08 volumio mpd[10742]: Apr 18 05:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 05:53:08 volumio systemd[1]: Started Music Player Daemon. Apr 18 05:53:08 volumio sudo[10626]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:08 volumio sudo[10636]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:08 volumio sudo[10722]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:08 volumio sudo[10769]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:08 volumio volumio-remote-updater[634]: [2025-04-18 03:53:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 05:53:08 volumio volumio-remote-updater[634]: [2025-04-18 03:53:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 05:53:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 05:53:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 05:53:08 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:08 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 05:53:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Apr 18 05:53:08 volumio systemd[1]: Stopped Volumio Backend Module. Apr 18 05:53:08 volumio systemd[1]: Started Volumio Backend Module. Apr 18 05:53:08 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:08 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:10 volumio volumio[10782]: info: ------------------------------------------- Apr 18 05:53:10 volumio volumio[10782]: info: ----- Volumio3 ---- Apr 18 05:53:10 volumio volumio[10782]: info: ------------------------------------------- Apr 18 05:53:10 volumio volumio[10782]: info: ----- System startup ---- Apr 18 05:53:10 volumio volumio[10782]: info: ------------------------------------------- Apr 18 05:53:10 volumio volumio[10782]: info: MYVOLUMIO Environment detected Apr 18 05:53:10 volumio volumio[10782]: info: Plugin folders cleanup Apr 18 05:53:10 volumio volumio[10782]: info: Scanning into folder /volumio/app/plugins/ Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category audio_interface Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category miscellanea Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category music_service Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category plugins.json Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category system_controller Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category user_interface Apr 18 05:53:10 volumio volumio[10782]: info: Scanning into folder /data/plugins/ Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category audio_interface Apr 18 05:53:10 volumio volumio[10782]: info: Scanning category music_service Apr 18 05:53:10 volumio volumio[10782]: info: Plugin folders cleanup completed Apr 18 05:53:10 volumio volumio[10782]: info: ------------------------------------------- Apr 18 05:53:10 volumio volumio[10782]: info: ----- Core plugins startup ---- Apr 18 05:53:10 volumio volumio[10782]: info: ------------------------------------------- Apr 18 05:53:10 volumio volumio[10782]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 05:53:10 volumio volumio[10782]: info: Adding plugin upnp to MyMusic Plugins Apr 18 05:53:10 volumio volumio[10782]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 05:53:10 volumio volumio[10782]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 05:53:10 volumio volumio[10782]: info: Loading plugins from folder /data/plugins/ Apr 18 05:53:10 volumio volumio[10782]: info: Loading plugin "system"... Apr 18 05:53:10 volumio volumio[10782]: info: Loading plugin "appearance"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "network"... Apr 18 05:53:11 volumio volumio[10782]: info: Refreshing Cached IP Addresses Apr 18 05:53:11 volumio sudo[10810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:11 volumio sudo[10810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:11 volumio sudo[10810]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:11 volumio sudo[10812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:11 volumio sudo[10812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:11 volumio sudo[10812]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "services"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "alsa_controller"... Apr 18 05:53:11 volumio sudo[10822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 05:53:11 volumio sudo[10822]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:11 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "wizard"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "networkfs"... Apr 18 05:53:11 volumio volumio[10782]: info: Starting Udev Watcher for removable devices Apr 18 05:53:11 volumio volumio[10782]: info: Ignoring mount for partition: boot Apr 18 05:53:11 volumio volumio[10782]: info: Ignoring mount for partition: volumio Apr 18 05:53:11 volumio volumio[10782]: info: Ignoring mount for partition: volumio_data Apr 18 05:53:11 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "volumio_command_line_client"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "upnp"... Apr 18 05:53:11 volumio volumio[10782]: info: [1744948391538] Starting Upmpd Daemon Apr 18 05:53:11 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "my_music"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "mpd"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "upnp_browser"... Apr 18 05:53:11 volumio kernel: hwmon hwmon1: Voltage normalised Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "alarm-clock"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "airplay_emulation"... Apr 18 05:53:11 volumio volumio[10782]: info: Starting Shairport Sync Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "last_100"... Apr 18 05:53:11 volumio volumio[10782]: info: Loading plugin "webradio"... Apr 18 05:53:12 volumio volumio[10782]: info: Loading plugin "i2s_dacs"... Apr 18 05:53:12 volumio volumio[10782]: info: I2S DAC not set, start Auto-detection Apr 18 05:53:12 volumio volumio[10782]: info: Loading plugin "volumiodiscovery"... Apr 18 05:53:12 volumio volumio[10782]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:12 volumio volumio[10782]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:12 volumio node[10782]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:12 volumio volumio[10782]: *** WARNING *** For more information see Apr 18 05:53:12 volumio volumio[10782]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:12 volumio volumio[10782]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:12 volumio volumio[10782]: *** WARNING *** For more information see Apr 18 05:53:12 volumio node[10782]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:12 volumio node[10782]: *** WARNING *** For more information see Apr 18 05:53:12 volumio node[10782]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:12 volumio node[10782]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:12 volumio node[10782]: *** WARNING *** For more information see Apr 18 05:53:12 volumio volumio[10782]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 05:53:12 volumio volumio[10782]: info: Discovery: Started advertising with name: Volumio Apr 18 05:53:12 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:12 volumio volumio[10782]: info: Loading plugin "spop"... Apr 18 05:53:13 volumio volumio[10782]: info: Loading plugin "outputs"... Apr 18 05:53:13 volumio volumio[10782]: info: Loading plugin "albumart"... Apr 18 05:53:13 volumio volumio[10782]: info: Plugin example_plugin is not enabled Apr 18 05:53:13 volumio volumio[10782]: info: Loading plugin "inputs"... Apr 18 05:53:13 volumio volumio-remote-updater[634]: [2025-04-18 03:53:13] [connect] Successful connection Apr 18 05:53:13 volumio volumio[10782]: info: Loading plugin "updater_comm"... Apr 18 05:53:13 volumio volumio[10782]: info: Plugin mpdemulation is not enabled Apr 18 05:53:13 volumio volumio[10782]: info: Loading plugin "rest_api"... Apr 18 05:53:13 volumio volumio[10782]: info: Loading plugin "websocket"... Apr 18 05:53:13 volumio volumio[10782]: info: Starting Socket.io Server version 2.3.0 Apr 18 05:53:13 volumio volumio[10782]: info: Loading plugin "fusiondsp"... Apr 18 05:53:13 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 18 05:53:14 volumio volumio[10782]: Forking 3 albumart workers Apr 18 05:53:14 volumio volumio[10782]: info: Applying required configuration parameters for plugin fusiondsp Apr 18 05:53:14 volumio volumio[10782]: info: Loading i18n strings for locale en Apr 18 05:53:14 volumio volumio[10782]: Updating browse sources language Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::initPlayerControls Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: Express server listening on port 3000 Apr 18 05:53:14 volumio volumio[10782]: [Metrics] WebUI: 4s 550.05ms Apr 18 05:53:14 volumio volumio[10782]: info: CoreStateMachine::resetVolumioState Apr 18 05:53:14 volumio volumio[10782]: info: CoreStateMachine::getcurrentVolume Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 05:53:14 volumio volumio[10782]: info: CoreStateMachine::pushState Apr 18 05:53:14 volumio volumio[10782]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:14 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:14 volumio volumio[10782]: info: Volumio Network Manager: Network status updated: 1 Apr 18 05:53:15 volumio volumio-remote-updater[634]: [2025-04-18 03:53:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744948393 101 Apr 18 05:53:15 volumio volumio[10782]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 18 05:53:15 volumio sudo[10822]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:15 volumio volumio[10782]: info: Reloading queue from file Apr 18 05:53:15 volumio volumio[10782]: info: CoreStateMachine::setRepeat null single undefined Apr 18 05:53:15 volumio volumio[10782]: info: CoreStateMachine::pushState Apr 18 05:53:15 volumio volumio[10782]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:15 volumio volumio[10782]: info: CoreStateMachine::setRandom null Apr 18 05:53:15 volumio volumio[10782]: info: CoreStateMachine::pushState Apr 18 05:53:15 volumio volumio[10782]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:15 volumio volumio[10782]: info: Setting Device type: Raspberry PI Apr 18 05:53:15 volumio volumio[10782]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 18 05:53:15 volumio volumio[10782]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Apr 18 05:53:15 volumio volumio[10782]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 18 05:53:15 volumio volumio[10782]: Starting albumart workers Apr 18 05:53:15 volumio volumio[10782]: Starting albumart workers Apr 18 05:53:15 volumio volumio[10782]: info: Completed loading Core Plugins Apr 18 05:53:15 volumio volumio[10782]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:15 volumio volumio[10782]: Starting albumart workers Apr 18 05:53:15 volumio volumio[10782]: info: Discovery: adding e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:15 volumio volumio[10782]: info: Discovery: Found device Volumio Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:15 volumio volumio[10782]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:15 volumio volumio[10782]: info: Discovery: this is already registered, e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:15 volumio volumio[10782]: info: Discovery: Found device Volumio Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:15 volumio volumio[10782]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:15 volumio volumio[10782]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:15 volumio volumio[10782]: info: Reading ALSA contributions from plugins. Apr 18 05:53:15 volumio volumio[10782]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:15 volumio volumio[10782]: info: Output device has changed, restarting MPD Apr 18 05:53:15 volumio volumio[10782]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:15 volumio sudo[10877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:15 volumio sudo[10877]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:15 volumio sudo[10878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:15 volumio sudo[10878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:15 volumio sudo[10877]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:15 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 05:53:15 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:15 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:15 volumio volumio[10782]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:15 volumio volumio[10782]: info: ___________ START PLUGINS ___________ Apr 18 05:53:15 volumio volumio[10782]: info: ControllerMpd::onStart: Initializing MPD Apr 18 05:53:15 volumio volumio[10782]: info: Creating MPD Configuration file Apr 18 05:53:15 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:15 volumio sudo[10886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:15 volumio sudo[10886]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:15 volumio sudo[10886]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:15 volumio volumio[10782]: info: [1744948395845] CoreMusicLibrary::Adding element Media Servers Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:15 volumio sudo[10884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:15 volumio sudo[10884]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:15 volumio sudo[10884]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:15 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:15 volumio sudo[10889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:15 volumio sudo[10889]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:16 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:16 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:16 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:16 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:16 volumio volumio[10782]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:16 volumio volumio[10782]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:16 volumio volumio[10782]: info: [1744948396247] CoreMusicLibrary::Adding element Last_100 Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:16 volumio sudo[10896]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:16 volumio sudo[10896]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:16 volumio volumio[10782]: info: [1744948396272] CoreMusicLibrary::Adding element Webradio Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:16 volumio sudo[10896]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:16 volumio volumio[10782]: info: Initializing BBC Radios Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:16 volumio volumio[10782]: info: Creating Spotify config file Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:16 volumio volumio[10782]: info: Loading i18n strings for locale en Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 18 05:53:16 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:16 volumio volumio[10782]: info: FusionDsp - mixtype--------------------- None Apr 18 05:53:16 volumio volumio[10782]: info: Volumio Calling Home Apr 18 05:53:16 volumio volumio[10782]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:16 volumio volumio[10782]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:16 volumio volumio[10782]: info: Reading ALSA contributions from plugins. Apr 18 05:53:16 volumio volumio[10782]: info: MPD Permissions set Apr 18 05:53:16 volumio volumio[10782]: info: MPD Permissions set Apr 18 05:53:16 volumio volumio[10782]: info: Volumio called home Apr 18 05:53:16 volumio volumio[10782]: info: Spotify config file written Apr 18 05:53:16 volumio sudo[10940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 05:53:16 volumio sudo[10940]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:17 volumio systemd[1]: Stopping go-librespot Daemon... Apr 18 05:53:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:17 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 18 05:53:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 05:53:17 volumio systemd[1]: Started go-librespot Daemon. Apr 18 05:53:17 volumio go-librespot[10942]: go-librespot daemon starting... Apr 18 05:53:17 volumio sudo[10940]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:17 volumio volumio[10782]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=info msg="running go-librespot 0.2.0" Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=debug msg="app state loaded" Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=debug msg="stored credentials not found" Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: No need to fix Spotify hosts Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 18 05:53:17 volumio go-librespot[10942]: time="2025-04-18T05:53:17+02:00" level=info msg="zeroconf server listening on port 46827" Apr 18 05:53:17 volumio volumio[10782]: info: Starting Shairport Sync Apr 18 05:53:17 volumio volumio[10782]: info: Starting Shairport Sync Apr 18 05:53:17 volumio sudo[10960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:17 volumio volumio[10782]: info: Starting Shairport Sync Apr 18 05:53:17 volumio sudo[10960]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:17 volumio sudo[10962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:17 volumio sudo[10962]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:17 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:17 volumio sudo[10966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:17 volumio sudo[10966]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:17 volumio volumio[10782]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:17 volumio sudo[10960]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:17 volumio sudo[10962]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:17 volumio volumio[10782]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:17 volumio volumio[10782]: info: Output device has changed, restarting MPD Apr 18 05:53:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:17 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:17 volumio sudo[10966]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:17 volumio volumio[10782]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:17 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:17 volumio sudo[10971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:17 volumio sudo[10971]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:17 volumio sudo[10973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:17 volumio sudo[10973]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:17 volumio sudo[10971]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:17 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:17 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:17 volumio volumio[10782]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:18 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:18 volumio sudo[10983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:18 volumio sudo[10983]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:18 volumio sudo[10983]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:18 volumio volumio[10782]: info: Shairport-Sync Started Apr 18 05:53:18 volumio sudo[10986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:18 volumio sudo[10986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:18 volumio volumio[10782]: Error adding Membership: Error: addMembership EINVAL Apr 18 05:53:18 volumio sudo[10986]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:18 volumio volumio[10782]: info: MPD Permissions set Apr 18 05:53:18 volumio volumio[10782]: info: Shairport-Sync Started Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 18 05:53:18 volumio sudo[10981]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:18 volumio sudo[10981]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:18 volumio volumio[10782]: info: Starting Shairport Sync Apr 18 05:53:18 volumio sudo[10981]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:18 volumio sudo[10995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:18 volumio sudo[10995]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 05:53:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:18 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:18 volumio sudo[10995]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:18 volumio volumio[10782]: info: Shairport-Sync Started Apr 18 05:53:18 volumio sudo[11001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:18 volumio sudo[11001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:18 volumio sudo[11001]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:18 volumio sudo[11003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:18 volumio sudo[11003]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:18 volumio sudo[11003]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:18 volumio volumio[10782]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:18 volumio volumio[10782]: 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 18 05:53:18 volumio volumio[10782]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:18 volumio volumio[10782]: info: camilladsp service started and running in background, instance 1 Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:18 volumio volumio[10782]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:18 volumio volumio[10782]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 18 05:53:18 volumio volumio[10782]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:18 volumio volumio[10782]: info: FusionDsp loaded Apr 18 05:53:18 volumio volumio[10782]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:18 volumio sudo[11016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 18 05:53:18 volumio sudo[11016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:18 volumio volumio[10782]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 18 05:53:18 volumio volumio[10782]: info: Adding Signal Path Element [object Object] Apr 18 05:53:18 volumio volumio[10782]: info: Adding fusiondspeq DSP Signal Path Element Apr 18 05:53:18 volumio volumio[10782]: info: FusionDsp - ---- installed callbackRead Apr 18 05:53:18 volumio volumio[10782]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:18 volumio volumio[10782]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 18 05:53:18 volumio volumio[10782]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 18 05:53:18 volumio volumio[10782]: at onErrorNT (internal/child_process.js:465:16) Apr 18 05:53:18 volumio volumio[10782]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 18 05:53:18 volumio volumio[10782]: errno: -2, Apr 18 05:53:18 volumio volumio[10782]: code: 'ENOENT', Apr 18 05:53:18 volumio volumio[10782]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:18 volumio volumio[10782]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:18 volumio volumio[10782]: spawnargs: [ Apr 18 05:53:18 volumio volumio[10782]: '-p', Apr 18 05:53:18 volumio volumio[10782]: 9876, Apr 18 05:53:18 volumio volumio[10782]: '-o', Apr 18 05:53:18 volumio volumio[10782]: '/tmp/camilladsp.log', Apr 18 05:53:18 volumio volumio[10782]: '-l', Apr 18 05:53:18 volumio volumio[10782]: 'warn', Apr 18 05:53:18 volumio volumio[10782]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 18 05:53:18 volumio volumio[10782]: ] Apr 18 05:53:18 volumio volumio[10782]: } Apr 18 05:53:18 volumio volumio[10782]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:18 volumio sudo[11016]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:19 volumio sudo[11027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:52 Apr 18 05:53:19 volumio sudo[11027]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:19 volumio sudo[11027]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:19 volumio volumio-remote-updater[634]: [2025-04-18 03:53:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 05:53:19 volumio volumio-remote-updater[634]: [2025-04-18 03:53:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 05:53:19 volumio mpd[10994]: Apr 18 05:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 05:53:19 volumio systemd[1]: Started Music Player Daemon. Apr 18 05:53:19 volumio sudo[10973]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:19 volumio sudo[10878]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:19 volumio sudo[10889]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 05:53:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 05:53:19 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:19 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 05:53:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Apr 18 05:53:19 volumio systemd[1]: Stopped Volumio Backend Module. Apr 18 05:53:19 volumio systemd[1]: Started Volumio Backend Module. Apr 18 05:53:19 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:19 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:21 volumio volumio[11039]: info: ------------------------------------------- Apr 18 05:53:21 volumio volumio[11039]: info: ----- Volumio3 ---- Apr 18 05:53:21 volumio volumio[11039]: info: ------------------------------------------- Apr 18 05:53:21 volumio volumio[11039]: info: ----- System startup ---- Apr 18 05:53:21 volumio volumio[11039]: info: ------------------------------------------- Apr 18 05:53:22 volumio volumio[11039]: info: MYVOLUMIO Environment detected Apr 18 05:53:22 volumio volumio[11039]: info: Plugin folders cleanup Apr 18 05:53:22 volumio volumio[11039]: info: Scanning into folder /volumio/app/plugins/ Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category audio_interface Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category miscellanea Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category music_service Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category plugins.json Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category system_controller Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category user_interface Apr 18 05:53:22 volumio volumio[11039]: info: Scanning into folder /data/plugins/ Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category audio_interface Apr 18 05:53:22 volumio volumio[11039]: info: Scanning category music_service Apr 18 05:53:22 volumio volumio[11039]: info: Plugin folders cleanup completed Apr 18 05:53:22 volumio volumio[11039]: info: ------------------------------------------- Apr 18 05:53:22 volumio volumio[11039]: info: ----- Core plugins startup ---- Apr 18 05:53:22 volumio volumio[11039]: info: ------------------------------------------- Apr 18 05:53:22 volumio volumio[11039]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 05:53:22 volumio volumio[11039]: info: Adding plugin upnp to MyMusic Plugins Apr 18 05:53:22 volumio volumio[11039]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 05:53:22 volumio volumio[11039]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 05:53:22 volumio volumio[11039]: info: Loading plugins from folder /data/plugins/ Apr 18 05:53:22 volumio volumio[11039]: info: Loading plugin "system"... Apr 18 05:53:22 volumio volumio[11039]: info: Loading plugin "appearance"... Apr 18 05:53:23 volumio volumio[11039]: info: Loading plugin "network"... Apr 18 05:53:23 volumio volumio[11039]: info: Refreshing Cached IP Addresses Apr 18 05:53:23 volumio sudo[11119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:23 volumio sudo[11119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:23 volumio sudo[11119]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:23 volumio sudo[11121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:23 volumio sudo[11121]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:23 volumio sudo[11121]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:23 volumio volumio[11039]: info: Loading plugin "services"... Apr 18 05:53:23 volumio volumio[11039]: info: Loading plugin "alsa_controller"... Apr 18 05:53:23 volumio sudo[11130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 05:53:23 volumio sudo[11130]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:24 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "wizard"... Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "networkfs"... Apr 18 05:53:24 volumio volumio[11039]: info: Starting Udev Watcher for removable devices Apr 18 05:53:24 volumio volumio[11039]: info: Ignoring mount for partition: boot Apr 18 05:53:24 volumio volumio[11039]: info: Ignoring mount for partition: volumio Apr 18 05:53:24 volumio volumio[11039]: info: Ignoring mount for partition: volumio_data Apr 18 05:53:24 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "volumio_command_line_client"... Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "upnp"... Apr 18 05:53:24 volumio volumio[11039]: info: [1744948404116] Starting Upmpd Daemon Apr 18 05:53:24 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "my_music"... Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "mpd"... Apr 18 05:53:24 volumio volumio-remote-updater[634]: [2025-04-18 03:53:24] [connect] Successful connection Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "upnp_browser"... Apr 18 05:53:24 volumio volumio[11039]: info: Loading plugin "alarm-clock"... Apr 18 05:53:25 volumio volumio[11039]: info: Loading plugin "airplay_emulation"... Apr 18 05:53:25 volumio volumio[11039]: info: Starting Shairport Sync Apr 18 05:53:25 volumio volumio[11039]: info: Loading plugin "last_100"... Apr 18 05:53:25 volumio volumio[11039]: info: Loading plugin "webradio"... Apr 18 05:53:25 volumio volumio[11039]: info: Loading plugin "i2s_dacs"... Apr 18 05:53:25 volumio volumio[11039]: info: I2S DAC not set, start Auto-detection Apr 18 05:53:25 volumio volumio[11039]: info: Loading plugin "volumiodiscovery"... Apr 18 05:53:25 volumio volumio[11039]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:25 volumio volumio[11039]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:25 volumio volumio[11039]: *** WARNING *** For more information see Apr 18 05:53:25 volumio node[11039]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:25 volumio volumio[11039]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:25 volumio volumio[11039]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:25 volumio volumio[11039]: *** WARNING *** For more information see Apr 18 05:53:25 volumio node[11039]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:25 volumio node[11039]: *** WARNING *** For more information see Apr 18 05:53:25 volumio node[11039]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:25 volumio node[11039]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:25 volumio node[11039]: *** WARNING *** For more information see Apr 18 05:53:25 volumio volumio[11039]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 05:53:25 volumio volumio[11039]: info: Discovery: Started advertising with name: Volumio Apr 18 05:53:25 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:25 volumio volumio[11039]: info: Loading plugin "spop"... Apr 18 05:53:26 volumio volumio[11039]: info: Loading plugin "outputs"... Apr 18 05:53:26 volumio volumio[11039]: info: Loading plugin "albumart"... Apr 18 05:53:26 volumio volumio[11039]: info: Plugin example_plugin is not enabled Apr 18 05:53:26 volumio volumio[11039]: info: Loading plugin "inputs"... Apr 18 05:53:26 volumio volumio[11039]: info: Loading plugin "updater_comm"... Apr 18 05:53:26 volumio volumio[11039]: info: Plugin mpdemulation is not enabled Apr 18 05:53:26 volumio volumio[11039]: info: Loading plugin "rest_api"... Apr 18 05:53:26 volumio volumio[11039]: info: Loading plugin "websocket"... Apr 18 05:53:26 volumio volumio[11039]: info: Starting Socket.io Server version 2.3.0 Apr 18 05:53:26 volumio volumio[11039]: info: Loading plugin "fusiondsp"... Apr 18 05:53:26 volumio volumio[11039]: Forking 3 albumart workers Apr 18 05:53:26 volumio volumio[11039]: info: Applying required configuration parameters for plugin fusiondsp Apr 18 05:53:26 volumio volumio[11039]: info: Loading i18n strings for locale en Apr 18 05:53:26 volumio volumio[11039]: Updating browse sources language Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::initPlayerControls Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:26 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:27 volumio volumio[11039]: Express server listening on port 3000 Apr 18 05:53:27 volumio volumio[11039]: [Metrics] WebUI: 5s 962.14ms Apr 18 05:53:27 volumio volumio[11039]: info: CoreStateMachine::resetVolumioState Apr 18 05:53:27 volumio volumio[11039]: info: CoreStateMachine::getcurrentVolume Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 05:53:27 volumio volumio[11039]: info: CoreStateMachine::pushState Apr 18 05:53:27 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:27 volumio volumio[11039]: info: Volumio Network Manager: Network status updated: 1 Apr 18 05:53:27 volumio volumio-remote-updater[634]: [2025-04-18 03:53:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744948404 101 Apr 18 05:53:27 volumio volumio[11039]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 18 05:53:27 volumio volumio[11039]: Starting albumart workers Apr 18 05:53:27 volumio volumio[11039]: info: Reloading queue from file Apr 18 05:53:27 volumio volumio[11039]: info: CoreStateMachine::setRepeat null single undefined Apr 18 05:53:27 volumio volumio[11039]: info: CoreStateMachine::pushState Apr 18 05:53:27 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:27 volumio volumio[11039]: info: CoreStateMachine::setRandom null Apr 18 05:53:27 volumio volumio[11039]: info: CoreStateMachine::pushState Apr 18 05:53:27 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:27 volumio volumio[11039]: Starting albumart workers Apr 18 05:53:27 volumio volumio[11039]: info: Setting Device type: Raspberry PI Apr 18 05:53:27 volumio volumio[11039]: Starting albumart workers Apr 18 05:53:27 volumio volumio[11039]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 18 05:53:27 volumio volumio[11039]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Apr 18 05:53:27 volumio volumio[11039]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 18 05:53:27 volumio sudo[11130]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:27 volumio volumio[11039]: info: Discovery: adding e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:27 volumio volumio[11039]: info: Discovery: Found device Volumio Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:27 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:27 volumio volumio[11039]: info: Completed loading Core Plugins Apr 18 05:53:27 volumio volumio[11039]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:27 volumio volumio[11039]: info: Discovery: this is already registered, e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:27 volumio volumio[11039]: info: Discovery: Found device Volumio Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:27 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:27 volumio volumio[11039]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:27 volumio volumio[11039]: info: Reading ALSA contributions from plugins. Apr 18 05:53:27 volumio volumio[11039]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:27 volumio volumio[11039]: info: Output device has changed, restarting MPD Apr 18 05:53:27 volumio volumio[11039]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:27 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:27 volumio sudo[11184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:27 volumio sudo[11184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:27 volumio sudo[11184]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:28 volumio sudo[11186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:28 volumio sudo[11186]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:28 volumio volumio[11039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:28 volumio volumio[11039]: info: ___________ START PLUGINS ___________ Apr 18 05:53:28 volumio volumio[11039]: info: ControllerMpd::onStart: Initializing MPD Apr 18 05:53:28 volumio volumio[11039]: info: Creating MPD Configuration file Apr 18 05:53:28 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 05:53:28 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:28 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:28 volumio sudo[11193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:28 volumio volumio[11039]: info: [1744948408179] CoreMusicLibrary::Adding element Media Servers Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:28 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:28 volumio sudo[11193]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:28 volumio sudo[11193]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:28 volumio sudo[11196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:28 volumio sudo[11196]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:28 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 18 05:53:28 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:28 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:28 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:28 volumio sudo[11199]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:28 volumio sudo[11199]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:28 volumio volumio[11039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:28 volumio sudo[11199]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:28 volumio volumio[11039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:28 volumio volumio[11039]: info: [1744948408663] CoreMusicLibrary::Adding element Last_100 Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:28 volumio volumio[11039]: info: [1744948408667] CoreMusicLibrary::Adding element Webradio Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:28 volumio volumio[11039]: info: Initializing BBC Radios Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:28 volumio volumio[11039]: info: Creating Spotify config file Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:28 volumio volumio[11039]: info: Loading i18n strings for locale en Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 18 05:53:28 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:28 volumio volumio[11039]: info: FusionDsp - mixtype--------------------- None Apr 18 05:53:28 volumio volumio[11039]: info: Volumio Calling Home Apr 18 05:53:29 volumio volumio[11039]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:29 volumio volumio[11039]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:29 volumio volumio[11039]: info: Reading ALSA contributions from plugins. Apr 18 05:53:29 volumio volumio[11039]: info: MPD Permissions set Apr 18 05:53:29 volumio volumio[11039]: info: MPD Permissions set Apr 18 05:53:29 volumio sudo[11248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:29 volumio sudo[11248]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:29 volumio sudo[11248]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:29 volumio volumio[11039]: info: Volumio called home Apr 18 05:53:29 volumio volumio[11039]: info: Spotify config file written Apr 18 05:53:29 volumio sudo[11250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:29 volumio sudo[11250]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:29 volumio sudo[11250]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:29 volumio sudo[11254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 05:53:29 volumio sudo[11254]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:29 volumio systemd[1]: Stopping go-librespot Daemon... Apr 18 05:53:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:29 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 18 05:53:29 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 05:53:29 volumio systemd[1]: Started go-librespot Daemon. Apr 18 05:53:29 volumio go-librespot[11256]: go-librespot daemon starting... Apr 18 05:53:29 volumio sudo[11254]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:29 volumio volumio[11039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=info msg="running go-librespot 0.2.0" Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=debug msg="app state loaded" Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=debug msg="stored credentials not found" Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:29 volumio volumio[11039]: info: No need to fix Spotify hosts Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 18 05:53:29 volumio go-librespot[11256]: time="2025-04-18T05:53:29+02:00" level=info msg="zeroconf server listening on port 33665" Apr 18 05:53:29 volumio volumio[11039]: info: Starting Shairport Sync Apr 18 05:53:29 volumio volumio[11039]: info: Starting Shairport Sync Apr 18 05:53:29 volumio sudo[11274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:29 volumio sudo[11274]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:29 volumio volumio[11039]: info: Starting Shairport Sync Apr 18 05:53:29 volumio sudo[11277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:29 volumio sudo[11277]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:29 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:30 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:30 volumio sudo[11280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:30 volumio sudo[11280]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:30 volumio sudo[11274]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:30 volumio volumio[11039]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:30 volumio volumio[11039]: info: Output device has changed, restarting MPD Apr 18 05:53:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:30 volumio volumio[11039]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:30 volumio sudo[11285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:30 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:30 volumio sudo[11285]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:30 volumio sudo[11277]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:30 volumio sudo[11285]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:30 volumio sudo[11287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:30 volumio sudo[11287]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:30 volumio sudo[11280]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:30 volumio volumio[11039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:30 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:30 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:30 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:30 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:30 volumio volumio[11039]: info: MPD Permissions set Apr 18 05:53:30 volumio volumio[11039]: info: Shairport-Sync Started Apr 18 05:53:30 volumio volumio[11039]: Error adding Membership: Error: addMembership EINVAL Apr 18 05:53:30 volumio volumio[11039]: info: Shairport-Sync Started Apr 18 05:53:30 volumio volumio[11039]: info: Shairport-Sync Started Apr 18 05:53:30 volumio volumio[11039]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:30 volumio volumio[11039]: info: Starting Shairport Sync Apr 18 05:53:30 volumio sudo[11296]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:30 volumio sudo[11296]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:30 volumio sudo[11296]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:30 volumio volumio[11039]: info: Received Get System Info Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:30 volumio volumio[11039]: info: Discovery: Getting this device information Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:30 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:30 volumio sudo[11304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:30 volumio sudo[11304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:30 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 05:53:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:30 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:30 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 05:53:30 volumio volumio[11039]: info: Received Get System Info Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:30 volumio volumio[11039]: info: Discovery: Getting this device information Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:30 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:30 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:30 volumio volumio[11039]: info: Listing playlists Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 05:53:30 volumio volumio[11039]: info: Received Get System Info Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:30 volumio volumio[11039]: info: Discovery: Getting this device information Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:30 volumio volumio[11039]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:30 volumio sudo[11304]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:30 volumio volumio[11039]: info: Shairport-Sync Started Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:30 volumio volumio[11039]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:30 volumio volumio[11039]: info: FusionDsp - Apr 18 05:53:30 volumio volumio[11039]: info: FusionDsp - Apr 18 05:53:31 volumio volumio[11039]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:31 volumio volumio[11039]: 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 18 05:53:31 volumio volumio[11039]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:31 volumio volumio[11039]: info: camilladsp service started and running in background, instance 1 Apr 18 05:53:31 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:31 volumio volumio[11039]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:31 volumio volumio[11039]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 18 05:53:31 volumio volumio[11039]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:31 volumio volumio[11039]: info: FusionDsp loaded Apr 18 05:53:31 volumio volumio[11039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:31 volumio sudo[11320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 18 05:53:31 volumio sudo[11320]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:31 volumio volumio[11039]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 18 05:53:31 volumio volumio[11039]: info: Adding Signal Path Element [object Object] Apr 18 05:53:31 volumio volumio[11039]: info: Adding fusiondspeq DSP Signal Path Element Apr 18 05:53:31 volumio volumio[11039]: info: FusionDsp - ---- installed callbackRead Apr 18 05:53:31 volumio volumio[11039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:31 volumio volumio[11039]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 18 05:53:31 volumio volumio[11039]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 18 05:53:31 volumio volumio[11039]: at onErrorNT (internal/child_process.js:465:16) Apr 18 05:53:31 volumio volumio[11039]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 05:53:31 volumio volumio[11039]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 18 05:53:31 volumio volumio[11039]: at listOnTimeout (internal/timers.js:523:9) Apr 18 05:53:31 volumio volumio[11039]: at processTimers (internal/timers.js:497:7) { Apr 18 05:53:31 volumio volumio[11039]: errno: -2, Apr 18 05:53:31 volumio volumio[11039]: code: 'ENOENT', Apr 18 05:53:31 volumio volumio[11039]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:31 volumio volumio[11039]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:31 volumio volumio[11039]: spawnargs: [ Apr 18 05:53:31 volumio volumio[11039]: '-p', Apr 18 05:53:31 volumio volumio[11039]: 9876, Apr 18 05:53:31 volumio volumio[11039]: '-o', Apr 18 05:53:31 volumio volumio[11039]: '/tmp/camilladsp.log', Apr 18 05:53:31 volumio volumio[11039]: '-l', Apr 18 05:53:31 volumio volumio[11039]: 'warn', Apr 18 05:53:31 volumio volumio[11039]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 18 05:53:31 volumio volumio[11039]: ] Apr 18 05:53:31 volumio volumio[11039]: } Apr 18 05:53:31 volumio volumio[11039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:31 volumio sudo[11320]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:32 volumio mpd[11305]: Apr 18 05:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 05:53:32 volumio systemd[1]: Started Music Player Daemon. Apr 18 05:53:32 volumio sudo[11287]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:32 volumio sudo[11186]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:32 volumio sudo[11196]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:32 volumio sudo[11334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:52 Apr 18 05:53:32 volumio sudo[11334]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:32 volumio sudo[11334]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:32 volumio volumio-remote-updater[634]: [2025-04-18 03:53:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 05:53:32 volumio volumio-remote-updater[634]: [2025-04-18 03:53:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 05:53:32 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 05:53:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 05:53:32 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:32 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:32 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 05:53:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Apr 18 05:53:32 volumio systemd[1]: Stopped Volumio Backend Module. Apr 18 05:53:32 volumio systemd[1]: Started Volumio Backend Module. Apr 18 05:53:32 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:32 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:34 volumio volumio[11344]: info: ------------------------------------------- Apr 18 05:53:34 volumio volumio[11344]: info: ----- Volumio3 ---- Apr 18 05:53:34 volumio volumio[11344]: info: ------------------------------------------- Apr 18 05:53:34 volumio volumio[11344]: info: ----- System startup ---- Apr 18 05:53:34 volumio volumio[11344]: info: ------------------------------------------- Apr 18 05:53:34 volumio volumio[11344]: info: MYVOLUMIO Environment detected Apr 18 05:53:34 volumio volumio[11344]: info: Plugin folders cleanup Apr 18 05:53:34 volumio volumio[11344]: info: Scanning into folder /volumio/app/plugins/ Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category audio_interface Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category miscellanea Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category music_service Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category plugins.json Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category system_controller Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category user_interface Apr 18 05:53:34 volumio volumio[11344]: info: Scanning into folder /data/plugins/ Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category audio_interface Apr 18 05:53:34 volumio volumio[11344]: info: Scanning category music_service Apr 18 05:53:34 volumio volumio[11344]: info: Plugin folders cleanup completed Apr 18 05:53:34 volumio volumio[11344]: info: ------------------------------------------- Apr 18 05:53:34 volumio volumio[11344]: info: ----- Core plugins startup ---- Apr 18 05:53:34 volumio volumio[11344]: info: ------------------------------------------- Apr 18 05:53:34 volumio volumio[11344]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 05:53:34 volumio volumio[11344]: info: Adding plugin upnp to MyMusic Plugins Apr 18 05:53:34 volumio volumio[11344]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 05:53:34 volumio volumio[11344]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 05:53:34 volumio volumio[11344]: info: Loading plugins from folder /data/plugins/ Apr 18 05:53:34 volumio volumio[11344]: info: Loading plugin "system"... Apr 18 05:53:34 volumio volumio[11344]: info: Loading plugin "appearance"... Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "network"... Apr 18 05:53:35 volumio volumio[11344]: info: Refreshing Cached IP Addresses Apr 18 05:53:35 volumio sudo[11371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:35 volumio sudo[11371]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:35 volumio sudo[11371]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:35 volumio sudo[11373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:35 volumio sudo[11373]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:35 volumio sudo[11373]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "services"... Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "alsa_controller"... Apr 18 05:53:35 volumio sudo[11382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 05:53:35 volumio sudo[11382]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:35 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "wizard"... Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "networkfs"... Apr 18 05:53:35 volumio volumio[11344]: info: Starting Udev Watcher for removable devices Apr 18 05:53:35 volumio volumio[11344]: info: Ignoring mount for partition: boot Apr 18 05:53:35 volumio volumio[11344]: info: Ignoring mount for partition: volumio Apr 18 05:53:35 volumio volumio[11344]: info: Ignoring mount for partition: volumio_data Apr 18 05:53:35 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "volumio_command_line_client"... Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "upnp"... Apr 18 05:53:35 volumio volumio[11344]: info: [1744948415915] Starting Upmpd Daemon Apr 18 05:53:35 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "my_music"... Apr 18 05:53:35 volumio volumio[11344]: info: Loading plugin "mpd"... Apr 18 05:53:36 volumio volumio[11344]: info: Loading plugin "upnp_browser"... Apr 18 05:53:36 volumio volumio[11344]: info: Loading plugin "alarm-clock"... Apr 18 05:53:36 volumio volumio[11344]: info: Loading plugin "airplay_emulation"... Apr 18 05:53:36 volumio volumio[11344]: info: Starting Shairport Sync Apr 18 05:53:36 volumio volumio[11344]: info: Loading plugin "last_100"... Apr 18 05:53:36 volumio volumio[11344]: info: Loading plugin "webradio"... Apr 18 05:53:37 volumio volumio[11344]: info: Loading plugin "i2s_dacs"... Apr 18 05:53:37 volumio volumio[11344]: info: I2S DAC not set, start Auto-detection Apr 18 05:53:37 volumio volumio[11344]: info: Loading plugin "volumiodiscovery"... Apr 18 05:53:37 volumio volumio[11344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:37 volumio volumio[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:37 volumio node[11344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:37 volumio volumio[11344]: *** WARNING *** For more information see Apr 18 05:53:37 volumio volumio[11344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:37 volumio volumio[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:37 volumio volumio[11344]: *** WARNING *** For more information see Apr 18 05:53:37 volumio node[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:37 volumio node[11344]: *** WARNING *** For more information see Apr 18 05:53:37 volumio node[11344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:37 volumio node[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:37 volumio node[11344]: *** WARNING *** For more information see Apr 18 05:53:37 volumio volumio[11344]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 05:53:37 volumio volumio[11344]: info: Discovery: Started advertising with name: Volumio Apr 18 05:53:37 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:37 volumio volumio[11344]: info: Loading plugin "spop"... Apr 18 05:53:37 volumio volumio-remote-updater[634]: [2025-04-18 03:53:37] [connect] Successful connection Apr 18 05:53:38 volumio volumio[11344]: info: Loading plugin "outputs"... Apr 18 05:53:38 volumio volumio[11344]: info: Loading plugin "albumart"... Apr 18 05:53:38 volumio volumio[11344]: info: Plugin example_plugin is not enabled Apr 18 05:53:38 volumio volumio[11344]: info: Loading plugin "inputs"... Apr 18 05:53:38 volumio volumio[11344]: info: Loading plugin "updater_comm"... Apr 18 05:53:38 volumio volumio[11344]: info: Plugin mpdemulation is not enabled Apr 18 05:53:38 volumio volumio[11344]: info: Loading plugin "rest_api"... Apr 18 05:53:38 volumio volumio[11344]: info: Loading plugin "websocket"... Apr 18 05:53:38 volumio volumio[11344]: info: Starting Socket.io Server version 2.3.0 Apr 18 05:53:38 volumio volumio[11344]: info: Loading plugin "fusiondsp"... Apr 18 05:53:38 volumio volumio[11344]: Forking 3 albumart workers Apr 18 05:53:38 volumio volumio[11344]: info: Applying required configuration parameters for plugin fusiondsp Apr 18 05:53:39 volumio volumio[11344]: info: Loading i18n strings for locale en Apr 18 05:53:39 volumio volumio[11344]: Updating browse sources language Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::initPlayerControls Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:39 volumio volumio[11344]: Express server listening on port 3000 Apr 18 05:53:39 volumio volumio[11344]: [Metrics] WebUI: 5s 230.54ms Apr 18 05:53:39 volumio volumio[11344]: info: CoreStateMachine::resetVolumioState Apr 18 05:53:39 volumio volumio[11344]: info: CoreStateMachine::getcurrentVolume Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 05:53:39 volumio volumio[11344]: info: CoreStateMachine::pushState Apr 18 05:53:39 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:39 volumio sudo[11382]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:39 volumio volumio[11344]: info: Volumio Network Manager: Network status updated: 1 Apr 18 05:53:39 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 18 05:53:39 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Apr 18 05:53:39 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 18 05:53:39 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Apr 18 05:53:39 volumio volumio-remote-updater[634]: [2025-04-18 03:53:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744948417 101 Apr 18 05:53:39 volumio volumio[11344]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Apr 18 05:53:39 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Apr 18 05:53:39 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Apr 18 05:53:39 volumio volumio[11344]: info: Reloading queue from file Apr 18 05:53:39 volumio volumio[11344]: info: CoreStateMachine::setRepeat null single undefined Apr 18 05:53:39 volumio volumio[11344]: info: CoreStateMachine::pushState Apr 18 05:53:39 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:39 volumio volumio[11344]: info: CoreStateMachine::setRandom null Apr 18 05:53:39 volumio volumio[11344]: info: CoreStateMachine::pushState Apr 18 05:53:39 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:39 volumio volumio[11344]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:39 volumio volumio[11344]: info: Setting Device type: Raspberry PI Apr 18 05:53:39 volumio volumio[11344]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 18 05:53:39 volumio volumio[11344]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Apr 18 05:53:39 volumio volumio[11344]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 18 05:53:39 volumio volumio[11344]: info: Completed loading Core Plugins Apr 18 05:53:39 volumio volumio[11344]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:39 volumio volumio[11344]: Starting albumart workers Apr 18 05:53:39 volumio volumio[11344]: Starting albumart workers Apr 18 05:53:40 volumio volumio[11344]: info: Discovery: adding e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:40 volumio volumio[11344]: info: Discovery: Found device Volumio Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:40 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:40 volumio volumio[11344]: Starting albumart workers Apr 18 05:53:40 volumio volumio[11344]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:40 volumio volumio[11344]: info: Reading ALSA contributions from plugins. Apr 18 05:53:40 volumio volumio[11344]: info: Discovery: this is already registered, e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:40 volumio volumio[11344]: info: Discovery: Found device Volumio Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:40 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:40 volumio volumio[11344]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:40 volumio volumio[11344]: info: Output device has changed, restarting MPD Apr 18 05:53:40 volumio volumio[11344]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:40 volumio sudo[11436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:40 volumio sudo[11436]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:40 volumio sudo[11436]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:40 volumio sudo[11438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:40 volumio sudo[11438]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:40 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 05:53:40 volumio volumio[11344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:40 volumio volumio[11344]: info: ___________ START PLUGINS ___________ Apr 18 05:53:40 volumio volumio[11344]: info: ControllerMpd::onStart: Initializing MPD Apr 18 05:53:40 volumio volumio[11344]: info: Creating MPD Configuration file Apr 18 05:53:40 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:40 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:40 volumio volumio[11344]: info: [1744948420378] CoreMusicLibrary::Adding element Media Servers Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:40 volumio sudo[11446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:40 volumio sudo[11446]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:40 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:40 volumio sudo[11446]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:40 volumio sudo[11448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:40 volumio sudo[11448]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:40 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 18 05:53:40 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:40 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:40 volumio volumio[11344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:40 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:40 volumio volumio[11344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:40 volumio volumio[11344]: info: [1744948420806] CoreMusicLibrary::Adding element Last_100 Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:40 volumio sudo[11454]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:40 volumio sudo[11454]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:40 volumio volumio[11344]: info: [1744948420820] CoreMusicLibrary::Adding element Webradio Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:40 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:40 volumio volumio[11344]: info: Initializing BBC Radios Apr 18 05:53:40 volumio sudo[11454]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: Creating Spotify config file Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: Loading i18n strings for locale en Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: FusionDsp - mixtype--------------------- None Apr 18 05:53:41 volumio volumio[11344]: info: Volumio Calling Home Apr 18 05:53:41 volumio volumio[11344]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:41 volumio volumio[11344]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:41 volumio volumio[11344]: info: Reading ALSA contributions from plugins. Apr 18 05:53:41 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Apr 18 05:53:41 volumio volumio[11344]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Apr 18 05:53:41 volumio volumio[11344]: info: MPD Permissions set Apr 18 05:53:41 volumio volumio[11344]: info: MPD Permissions set Apr 18 05:53:41 volumio volumio[11344]: info: Volumio called home Apr 18 05:53:41 volumio volumio[11344]: info: Spotify config file written Apr 18 05:53:41 volumio sudo[11498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 05:53:41 volumio sudo[11498]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:41 volumio systemd[1]: Stopping go-librespot Daemon... Apr 18 05:53:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:41 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 18 05:53:41 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 05:53:41 volumio systemd[1]: Started go-librespot Daemon. Apr 18 05:53:41 volumio go-librespot[11500]: go-librespot daemon starting... Apr 18 05:53:41 volumio sudo[11498]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:41 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:41 volumio volumio[11344]: 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 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio go-librespot[11500]: time="2025-04-18T05:53:41+02:00" level=info msg="running go-librespot 0.2.0" Apr 18 05:53:41 volumio go-librespot[11500]: time="2025-04-18T05:53:41+02:00" level=debug msg="app state loaded" Apr 18 05:53:41 volumio go-librespot[11500]: time="2025-04-18T05:53:41+02:00" level=debug msg="stored credentials not found" Apr 18 05:53:41 volumio go-librespot[11500]: time="2025-04-18T05:53:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:41 volumio volumio[11344]: info: No need to fix Spotify hosts Apr 18 05:53:42 volumio go-librespot[11500]: time="2025-04-18T05:53:42+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 18 05:53:42 volumio go-librespot[11500]: time="2025-04-18T05:53:42+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 18 05:53:42 volumio go-librespot[11500]: time="2025-04-18T05:53:42+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 18 05:53:42 volumio volumio[11344]: info: Received Get System Info Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:42 volumio go-librespot[11500]: time="2025-04-18T05:53:42+02:00" level=info msg="zeroconf server listening on port 36055" Apr 18 05:53:42 volumio volumio[11344]: info: Discovery: Getting this device information Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:42 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:42 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:42 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:42 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 05:53:42 volumio volumio[11344]: info: Received Get System Info Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:42 volumio volumio[11344]: info: Discovery: Getting this device information Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:42 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:42 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:42 volumio volumio[11344]: info: Listing playlists Apr 18 05:53:42 volumio volumio[11344]: info: Starting Shairport Sync Apr 18 05:53:42 volumio volumio[11344]: info: Starting Shairport Sync Apr 18 05:53:42 volumio sudo[11517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:42 volumio sudo[11517]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:42 volumio volumio[11344]: info: Starting Shairport Sync Apr 18 05:53:42 volumio sudo[11520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:42 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:42 volumio sudo[11520]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:42 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:42 volumio sudo[11522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:42 volumio sudo[11522]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:42 volumio volumio[11344]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:42 volumio volumio[11344]: info: Output device has changed, restarting MPD Apr 18 05:53:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:42 volumio sudo[11517]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:42 volumio sudo[11528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:42 volumio sudo[11528]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:42 volumio sudo[11528]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:42 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:42 volumio volumio[11344]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:42 volumio sudo[11522]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:42 volumio sudo[11520]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:42 volumio sudo[11532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:42 volumio sudo[11532]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:42 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:42 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:42 volumio volumio[11344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:42 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:42 volumio volumio[11344]: info: MPD Permissions set Apr 18 05:53:42 volumio volumio[11344]: info: Shairport-Sync Started Apr 18 05:53:42 volumio volumio[11344]: Error adding Membership: Error: addMembership EINVAL Apr 18 05:53:42 volumio volumio[11344]: info: Shairport-Sync Started Apr 18 05:53:42 volumio volumio[11344]: info: Shairport-Sync Started Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:42 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:42 volumio volumio[11344]: info: FusionDsp - Apr 18 05:53:42 volumio sudo[11540]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:42 volumio sudo[11540]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:42 volumio sudo[11540]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:42 volumio volumio[11344]: info: FusionDsp - Apr 18 05:53:43 volumio volumio[11344]: info: FusionDsp - Apr 18 05:53:43 volumio volumio[11344]: info: FusionDsp - Apr 18 05:53:43 volumio volumio[11344]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:43 volumio volumio[11344]: 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 18 05:53:43 volumio volumio[11344]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:43 volumio volumio[11344]: info: camilladsp service started and running in background, instance 1 Apr 18 05:53:43 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:43 volumio volumio[11344]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:43 volumio volumio[11344]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 18 05:53:43 volumio volumio[11344]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:43 volumio volumio[11344]: info: FusionDsp loaded Apr 18 05:53:43 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:43 volumio sudo[11557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 18 05:53:43 volumio sudo[11557]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:43 volumio volumio[11344]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 18 05:53:43 volumio volumio[11344]: info: Adding Signal Path Element [object Object] Apr 18 05:53:43 volumio volumio[11344]: info: Adding fusiondspeq DSP Signal Path Element Apr 18 05:53:43 volumio volumio[11344]: info: FusionDsp - ---- installed callbackRead Apr 18 05:53:43 volumio volumio[11344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:43 volumio volumio[11344]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 18 05:53:43 volumio volumio[11344]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 18 05:53:43 volumio volumio[11344]: at onErrorNT (internal/child_process.js:465:16) Apr 18 05:53:43 volumio volumio[11344]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 05:53:43 volumio volumio[11344]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 18 05:53:43 volumio volumio[11344]: at listOnTimeout (internal/timers.js:523:9) Apr 18 05:53:43 volumio volumio[11344]: at processTimers (internal/timers.js:497:7) { Apr 18 05:53:43 volumio volumio[11344]: errno: -2, Apr 18 05:53:43 volumio volumio[11344]: code: 'ENOENT', Apr 18 05:53:43 volumio volumio[11344]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:43 volumio volumio[11344]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:43 volumio volumio[11344]: spawnargs: [ Apr 18 05:53:43 volumio volumio[11344]: '-p', Apr 18 05:53:43 volumio volumio[11344]: 9876, Apr 18 05:53:43 volumio volumio[11344]: '-o', Apr 18 05:53:43 volumio volumio[11344]: '/tmp/camilladsp.log', Apr 18 05:53:43 volumio volumio[11344]: '-l', Apr 18 05:53:43 volumio volumio[11344]: 'warn', Apr 18 05:53:43 volumio volumio[11344]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 18 05:53:43 volumio volumio[11344]: ] Apr 18 05:53:43 volumio volumio[11344]: } Apr 18 05:53:43 volumio volumio[11344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:43 volumio sudo[11557]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:44 volumio mpd[11546]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 05:53:44 volumio mpd[11546]: output: No 'audio_output' defined in config file Apr 18 05:53:44 volumio mpd[11546]: output: Successfully detected a sndio audio device Apr 18 05:53:44 volumio mpd[11546]: zeroconf: No global port, disabling zeroconf Apr 18 05:53:44 volumio systemd[1]: Started Music Player Daemon. Apr 18 05:53:44 volumio sudo[11448]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:44 volumio sudo[11532]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:44 volumio sudo[11438]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:44 volumio sudo[11570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:52 Apr 18 05:53:44 volumio sudo[11570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:44 volumio sudo[11570]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:45 volumio volumio-remote-updater[634]: [2025-04-18 03:53:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 05:53:45 volumio volumio-remote-updater[634]: [2025-04-18 03:53:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 05:53:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 05:53:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 05:53:45 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:45 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 05:53:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Apr 18 05:53:45 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:45 volumio systemd[1]: Stopped Volumio Backend Module. Apr 18 05:53:45 volumio systemd[1]: Started Volumio Backend Module. Apr 18 05:53:45 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:46 volumio volumio[11581]: info: ------------------------------------------- Apr 18 05:53:46 volumio volumio[11581]: info: ----- Volumio3 ---- Apr 18 05:53:46 volumio volumio[11581]: info: ------------------------------------------- Apr 18 05:53:46 volumio volumio[11581]: info: ----- System startup ---- Apr 18 05:53:46 volumio volumio[11581]: info: ------------------------------------------- Apr 18 05:53:46 volumio volumio[11581]: info: MYVOLUMIO Environment detected Apr 18 05:53:46 volumio volumio[11581]: info: Plugin folders cleanup Apr 18 05:53:46 volumio volumio[11581]: info: Scanning into folder /volumio/app/plugins/ Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category audio_interface Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category miscellanea Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category music_service Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category plugins.json Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category system_controller Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category user_interface Apr 18 05:53:46 volumio volumio[11581]: info: Scanning into folder /data/plugins/ Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category audio_interface Apr 18 05:53:46 volumio volumio[11581]: info: Scanning category music_service Apr 18 05:53:46 volumio volumio[11581]: info: Plugin folders cleanup completed Apr 18 05:53:46 volumio volumio[11581]: info: ------------------------------------------- Apr 18 05:53:46 volumio volumio[11581]: info: ----- Core plugins startup ---- Apr 18 05:53:46 volumio volumio[11581]: info: ------------------------------------------- Apr 18 05:53:46 volumio volumio[11581]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 05:53:46 volumio volumio[11581]: info: Adding plugin upnp to MyMusic Plugins Apr 18 05:53:46 volumio volumio[11581]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 05:53:46 volumio volumio[11581]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 05:53:46 volumio volumio[11581]: info: Loading plugins from folder /data/plugins/ Apr 18 05:53:46 volumio volumio[11581]: info: Loading plugin "system"... Apr 18 05:53:46 volumio volumio[11581]: info: Loading plugin "appearance"... Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "network"... Apr 18 05:53:47 volumio volumio[11581]: info: Refreshing Cached IP Addresses Apr 18 05:53:47 volumio sudo[11607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:47 volumio sudo[11607]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:47 volumio sudo[11607]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:47 volumio sudo[11609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:47 volumio sudo[11609]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:47 volumio sudo[11609]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "services"... Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "alsa_controller"... Apr 18 05:53:47 volumio sudo[11621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 05:53:47 volumio sudo[11621]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:47 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "wizard"... Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "networkfs"... Apr 18 05:53:47 volumio volumio[11581]: info: Starting Udev Watcher for removable devices Apr 18 05:53:47 volumio volumio[11581]: info: Ignoring mount for partition: boot Apr 18 05:53:47 volumio volumio[11581]: info: Ignoring mount for partition: volumio Apr 18 05:53:47 volumio volumio[11581]: info: Ignoring mount for partition: volumio_data Apr 18 05:53:47 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "volumio_command_line_client"... Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "upnp"... Apr 18 05:53:47 volumio volumio[11581]: info: [1744948427565] Starting Upmpd Daemon Apr 18 05:53:47 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "my_music"... Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "mpd"... Apr 18 05:53:47 volumio volumio[11581]: info: Loading plugin "upnp_browser"... Apr 18 05:53:48 volumio volumio[11581]: info: Loading plugin "alarm-clock"... Apr 18 05:53:48 volumio volumio[11581]: info: Loading plugin "airplay_emulation"... Apr 18 05:53:48 volumio volumio[11581]: info: Starting Shairport Sync Apr 18 05:53:48 volumio volumio[11581]: info: Loading plugin "last_100"... Apr 18 05:53:48 volumio volumio[11581]: info: Loading plugin "webradio"... Apr 18 05:53:48 volumio volumio[11581]: info: Loading plugin "i2s_dacs"... Apr 18 05:53:48 volumio volumio[11581]: info: I2S DAC not set, start Auto-detection Apr 18 05:53:48 volumio volumio[11581]: info: Loading plugin "volumiodiscovery"... Apr 18 05:53:48 volumio volumio[11581]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:48 volumio volumio[11581]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:48 volumio volumio[11581]: *** WARNING *** For more information see Apr 18 05:53:48 volumio node[11581]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:48 volumio volumio[11581]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:48 volumio volumio[11581]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:48 volumio volumio[11581]: *** WARNING *** For more information see Apr 18 05:53:48 volumio node[11581]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:48 volumio node[11581]: *** WARNING *** For more information see Apr 18 05:53:48 volumio node[11581]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:53:48 volumio node[11581]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:53:48 volumio node[11581]: *** WARNING *** For more information see Apr 18 05:53:48 volumio volumio[11581]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 05:53:48 volumio volumio[11581]: info: Discovery: Started advertising with name: Volumio Apr 18 05:53:48 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:53:48 volumio volumio[11581]: info: Loading plugin "spop"... Apr 18 05:53:49 volumio volumio[11581]: info: Loading plugin "outputs"... Apr 18 05:53:49 volumio volumio[11581]: info: Loading plugin "albumart"... Apr 18 05:53:50 volumio volumio[11581]: info: Plugin example_plugin is not enabled Apr 18 05:53:50 volumio volumio[11581]: info: Loading plugin "inputs"... Apr 18 05:53:50 volumio volumio[11581]: info: Loading plugin "updater_comm"... Apr 18 05:53:50 volumio volumio-remote-updater[634]: [2025-04-18 03:53:50] [connect] Successful connection Apr 18 05:53:50 volumio volumio[11581]: info: Plugin mpdemulation is not enabled Apr 18 05:53:50 volumio volumio[11581]: info: Loading plugin "rest_api"... Apr 18 05:53:50 volumio volumio[11581]: info: Loading plugin "websocket"... Apr 18 05:53:50 volumio volumio[11581]: info: Starting Socket.io Server version 2.3.0 Apr 18 05:53:50 volumio volumio[11581]: info: Loading plugin "fusiondsp"... Apr 18 05:53:50 volumio volumio[11581]: Forking 3 albumart workers Apr 18 05:53:50 volumio volumio[11581]: info: Applying required configuration parameters for plugin fusiondsp Apr 18 05:53:50 volumio volumio[11581]: info: Loading i18n strings for locale en Apr 18 05:53:50 volumio volumio[11581]: Updating browse sources language Apr 18 05:53:50 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:50 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::initPlayerControls Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: Express server listening on port 3000 Apr 18 05:53:51 volumio volumio[11581]: [Metrics] WebUI: 4s 932.02ms Apr 18 05:53:51 volumio sudo[11621]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:51 volumio volumio[11581]: info: CoreStateMachine::resetVolumioState Apr 18 05:53:51 volumio volumio[11581]: info: CoreStateMachine::getcurrentVolume Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 05:53:51 volumio volumio[11581]: info: CoreStateMachine::pushState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:51 volumio volumio[11581]: info: Volumio Network Manager: Network status updated: 1 Apr 18 05:53:51 volumio volumio[11581]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 18 05:53:51 volumio volumio[11581]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Apr 18 05:53:51 volumio volumio[11581]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 18 05:53:51 volumio volumio[11581]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Apr 18 05:53:51 volumio volumio-remote-updater[634]: [2025-04-18 03:53:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744948430 101 Apr 18 05:53:51 volumio volumio[11581]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Apr 18 05:53:51 volumio volumio[11581]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Apr 18 05:53:51 volumio volumio[11581]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Apr 18 05:53:51 volumio volumio[11581]: info: Reloading queue from file Apr 18 05:53:51 volumio volumio[11581]: info: CoreStateMachine::setRepeat null single undefined Apr 18 05:53:51 volumio volumio[11581]: info: CoreStateMachine::pushState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:51 volumio volumio[11581]: info: CoreStateMachine::setRandom null Apr 18 05:53:51 volumio volumio[11581]: info: CoreStateMachine::pushState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioPushState Apr 18 05:53:51 volumio volumio[11581]: info: Setting Device type: Raspberry PI Apr 18 05:53:51 volumio volumio[11581]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 18 05:53:51 volumio volumio[11581]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Apr 18 05:53:51 volumio volumio[11581]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 18 05:53:51 volumio volumio[11581]: Starting albumart workers Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: Starting albumart workers Apr 18 05:53:51 volumio volumio[11581]: Starting albumart workers Apr 18 05:53:51 volumio volumio[11581]: info: Received Get System Info Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:51 volumio volumio[11581]: info: Discovery: Getting this device information Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 05:53:51 volumio volumio[11581]: info: Received Get System Info Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:51 volumio volumio[11581]: info: Discovery: Getting this device information Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: Listing playlists Apr 18 05:53:51 volumio volumio[11581]: info: Discovery: adding e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:51 volumio volumio[11581]: info: Discovery: Found device Volumio Apr 18 05:53:51 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:51 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:51 volumio volumio[11581]: info: Discovery: this is already registered, e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:53:52 volumio volumio[11581]: info: Discovery: Found device Volumio Apr 18 05:53:52 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:52 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:52 volumio volumio[11581]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:52 volumio volumio[11581]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:52 volumio volumio[11581]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:52 volumio volumio[11581]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:52 volumio volumio[11581]: info: Completed loading Core Plugins Apr 18 05:53:52 volumio volumio[11581]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:52 volumio volumio[11581]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:52 volumio volumio[11581]: info: Reading ALSA contributions from plugins. Apr 18 05:53:52 volumio volumio[11581]: info: FusionDsp - Apr 18 05:53:52 volumio volumio[11581]: info: FusionDsp - Apr 18 05:53:52 volumio volumio[11581]: info: FusionDsp - Apr 18 05:53:53 volumio volumio[11581]: info: FusionDsp - Apr 18 05:53:53 volumio volumio[11581]: info: FusionDsp - undefined Apr 18 05:53:53 volumio volumio[11581]: info: FusionDsp - undefined Apr 18 05:53:53 volumio volumio[11581]: info: FusionDsp - undefined Apr 18 05:53:53 volumio volumio[11581]: info: FusionDsp - undefined Apr 18 05:53:53 volumio volumio[11581]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:53 volumio volumio[11581]: info: Output device has changed, restarting MPD Apr 18 05:53:53 volumio sudo[11700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:53 volumio sudo[11700]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:53 volumio volumio[11581]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:53 volumio sudo[11700]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:53 volumio sudo[11703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:53 volumio sudo[11703]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:53 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 05:53:53 volumio volumio[11581]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:53 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:53 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:53 volumio volumio[11581]: info: ___________ START PLUGINS ___________ Apr 18 05:53:53 volumio volumio[11581]: info: ControllerMpd::onStart: Initializing MPD Apr 18 05:53:53 volumio volumio[11581]: info: Creating MPD Configuration file Apr 18 05:53:53 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:53 volumio sudo[11710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:53 volumio sudo[11710]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:53 volumio sudo[11710]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:53 volumio volumio[11581]: info: [1744948433799] CoreMusicLibrary::Adding element Media Servers Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:53 volumio sudo[11713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:53 volumio sudo[11713]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:53 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 18 05:53:53 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:53 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:53 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:53 volumio volumio[11581]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:53 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:54 volumio volumio[11581]: info: [1744948434026] CoreMusicLibrary::Adding element Last_100 Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:53:54 volumio volumio[11581]: info: [1744948434032] CoreMusicLibrary::Adding element Webradio Apr 18 05:53:54 volumio sudo[11716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:54 volumio sudo[11716]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:54 volumio sudo[11716]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:54 volumio volumio[11581]: info: Initializing BBC Radios Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: Creating Spotify config file Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: Loading i18n strings for locale en Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: FusionDsp - mixtype--------------------- None Apr 18 05:53:54 volumio volumio[11581]: info: Volumio Calling Home Apr 18 05:53:54 volumio volumio[11581]: info: Preparing to generate the ALSA configuration file Apr 18 05:53:54 volumio volumio[11581]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:53:54 volumio volumio[11581]: info: Reading ALSA contributions from plugins. Apr 18 05:53:54 volumio volumio[11581]: info: MPD Permissions set Apr 18 05:53:54 volumio volumio[11581]: info: MPD Permissions set Apr 18 05:53:54 volumio volumio[11581]: info: Volumio called home Apr 18 05:53:54 volumio volumio[11581]: info: Spotify config file written Apr 18 05:53:54 volumio sudo[11738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 05:53:54 volumio sudo[11738]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:54 volumio systemd[1]: Stopping go-librespot Daemon... Apr 18 05:53:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:54 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 18 05:53:54 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 05:53:54 volumio volumio[11581]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio systemd[1]: Started go-librespot Daemon. Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:54 volumio go-librespot[11740]: go-librespot daemon starting... Apr 18 05:53:54 volumio volumio[11581]: info: No need to fix Spotify hosts Apr 18 05:53:54 volumio sudo[11738]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=info msg="running go-librespot 0.2.0" Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=debug msg="app state loaded" Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=debug msg="stored credentials not found" Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 05:53:54 volumio volumio[11581]: info: Starting Shairport Sync Apr 18 05:53:54 volumio volumio[11581]: info: Starting Shairport Sync Apr 18 05:53:54 volumio sudo[11758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:54 volumio sudo[11758]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:54 volumio volumio[11581]: info: Starting Shairport Sync Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 18 05:53:54 volumio go-librespot[11740]: time="2025-04-18T05:53:54+02:00" level=info msg="zeroconf server listening on port 37593" Apr 18 05:53:54 volumio sudo[11761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:54 volumio sudo[11761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:54 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:54 volumio sudo[11763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:54 volumio sudo[11763]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:54 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:54 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:54 volumio sudo[11758]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:54 volumio volumio[11581]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:53:54 volumio volumio[11581]: info: Output device has changed, restarting MPD Apr 18 05:53:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:55 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:55 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:55 volumio sudo[11763]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:55 volumio sudo[11761]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:55 volumio sudo[11769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:53:55 volumio sudo[11769]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:55 volumio volumio[11581]: info: Output device has changed, restarting Shairport Sync Apr 18 05:53:55 volumio sudo[11769]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:55 volumio sudo[11772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:53:55 volumio sudo[11772]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:55 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:53:55 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:53:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:53:55 volumio volumio[11581]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:53:55 volumio volumio[11581]: info: Shairport-Sync Started Apr 18 05:53:55 volumio volumio[11581]: Error adding Membership: Error: addMembership EINVAL Apr 18 05:53:55 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:53:55 volumio volumio[11581]: info: MPD Permissions set Apr 18 05:53:55 volumio volumio[11581]: info: Shairport-Sync Started Apr 18 05:53:55 volumio volumio[11581]: info: Shairport-Sync Started Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio sudo[11779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:53:55 volumio sudo[11779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:55 volumio sudo[11779]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:55 volumio volumio[11581]: info: Starting Shairport Sync Apr 18 05:53:55 volumio sudo[11788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:53:55 volumio sudo[11788]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:53:55 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:53:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:55 volumio sudo[11792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:53:55 volumio sudo[11792]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:53:55 volumio sudo[11792]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:55 volumio sudo[11788]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:55 volumio volumio[11581]: info: Shairport-Sync Started Apr 18 05:53:55 volumio sudo[11795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:53:55 volumio sudo[11795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:55 volumio sudo[11795]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:55 volumio volumio[11581]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:55 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 05:53:55 volumio volumio[11581]: info: Received Get System Info Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:53:55 volumio volumio[11581]: info: Discovery: Getting this device information Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:55 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:55 volumio volumio[11581]: info: CoreCommandRouter::volumioGetState Apr 18 05:53:55 volumio volumio[11581]: info: CorePlayQueue::getTrack 0 Apr 18 05:53:55 volumio volumio[11581]: info: Listing playlists Apr 18 05:53:56 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 05:53:56 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 05:53:56 volumio volumio[11581]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:56 volumio volumio[11581]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:53:56 volumio volumio[11581]: info: FusionDsp - Apr 18 05:53:56 volumio volumio[11581]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:56 volumio volumio[11581]: 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 18 05:53:56 volumio volumio[11581]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:53:56 volumio volumio[11581]: info: camilladsp service started and running in background, instance 1 Apr 18 05:53:56 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:53:56 volumio volumio[11581]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:56 volumio volumio[11581]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 18 05:53:56 volumio volumio[11581]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:53:56 volumio volumio[11581]: info: FusionDsp loaded Apr 18 05:53:56 volumio volumio[11581]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:53:56 volumio sudo[11809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 18 05:53:56 volumio sudo[11809]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:56 volumio volumio[11581]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 18 05:53:56 volumio volumio[11581]: info: Adding Signal Path Element [object Object] Apr 18 05:53:56 volumio volumio[11581]: info: Adding fusiondspeq DSP Signal Path Element Apr 18 05:53:56 volumio volumio[11581]: info: FusionDsp - ---- installed callbackRead Apr 18 05:53:56 volumio volumio[11581]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:56 volumio volumio[11581]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 18 05:53:56 volumio volumio[11581]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 18 05:53:56 volumio volumio[11581]: at onErrorNT (internal/child_process.js:465:16) Apr 18 05:53:56 volumio volumio[11581]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 05:53:56 volumio volumio[11581]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 18 05:53:56 volumio volumio[11581]: at listOnTimeout (internal/timers.js:523:9) Apr 18 05:53:56 volumio volumio[11581]: at processTimers (internal/timers.js:497:7) { Apr 18 05:53:56 volumio volumio[11581]: errno: -2, Apr 18 05:53:56 volumio volumio[11581]: code: 'ENOENT', Apr 18 05:53:56 volumio volumio[11581]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:56 volumio volumio[11581]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:53:56 volumio volumio[11581]: spawnargs: [ Apr 18 05:53:56 volumio volumio[11581]: '-p', Apr 18 05:53:56 volumio volumio[11581]: 9876, Apr 18 05:53:56 volumio volumio[11581]: '-o', Apr 18 05:53:56 volumio volumio[11581]: '/tmp/camilladsp.log', Apr 18 05:53:56 volumio volumio[11581]: '-l', Apr 18 05:53:56 volumio volumio[11581]: 'warn', Apr 18 05:53:56 volumio volumio[11581]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 18 05:53:56 volumio volumio[11581]: ] Apr 18 05:53:56 volumio volumio[11581]: } Apr 18 05:53:56 volumio volumio[11581]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:53:56 volumio sudo[11809]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:57 volumio mpd[11786]: Apr 18 05:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 05:53:57 volumio systemd[1]: Started Music Player Daemon. Apr 18 05:53:57 volumio sudo[11703]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:57 volumio sudo[11772]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:57 volumio sudo[11713]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:57 volumio sudo[11822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:52 Apr 18 05:53:57 volumio sudo[11822]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:53:57 volumio sudo[11822]: pam_unix(sudo:session): session closed for user root Apr 18 05:53:57 volumio volumio-remote-updater[634]: [2025-04-18 03:53:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 05:53:57 volumio volumio-remote-updater[634]: [2025-04-18 03:53:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 05:53:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 05:53:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 05:53:57 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:57 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:58 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 05:53:58 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Apr 18 05:53:58 volumio systemd[1]: Started dynamicswap service. Apr 18 05:53:58 volumio systemd[1]: Stopped Volumio Backend Module. Apr 18 05:53:58 volumio systemd[1]: Started Volumio Backend Module. Apr 18 05:53:58 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:53:59 volumio volumio[11847]: info: ------------------------------------------- Apr 18 05:53:59 volumio volumio[11847]: info: ----- Volumio3 ---- Apr 18 05:53:59 volumio volumio[11847]: info: ------------------------------------------- Apr 18 05:53:59 volumio volumio[11847]: info: ----- System startup ---- Apr 18 05:53:59 volumio volumio[11847]: info: ------------------------------------------- Apr 18 05:53:59 volumio volumio[11847]: info: MYVOLUMIO Environment detected Apr 18 05:53:59 volumio volumio[11847]: info: Plugin folders cleanup Apr 18 05:53:59 volumio volumio[11847]: info: Scanning into folder /volumio/app/plugins/ Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category audio_interface Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category miscellanea Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category music_service Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category plugins.json Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category system_controller Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category user_interface Apr 18 05:53:59 volumio volumio[11847]: info: Scanning into folder /data/plugins/ Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category audio_interface Apr 18 05:53:59 volumio volumio[11847]: info: Scanning category music_service Apr 18 05:53:59 volumio volumio[11847]: info: Plugin folders cleanup completed Apr 18 05:53:59 volumio volumio[11847]: info: ------------------------------------------- Apr 18 05:53:59 volumio volumio[11847]: info: ----- Core plugins startup ---- Apr 18 05:53:59 volumio volumio[11847]: info: ------------------------------------------- Apr 18 05:53:59 volumio volumio[11847]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 05:53:59 volumio volumio[11847]: info: Adding plugin upnp to MyMusic Plugins Apr 18 05:53:59 volumio volumio[11847]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 05:53:59 volumio volumio[11847]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 05:53:59 volumio volumio[11847]: info: Loading plugins from folder /data/plugins/ Apr 18 05:53:59 volumio volumio[11847]: info: Loading plugin "system"... Apr 18 05:53:59 volumio volumio[11847]: info: Loading plugin "appearance"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "network"... Apr 18 05:54:00 volumio volumio[11847]: info: Refreshing Cached IP Addresses Apr 18 05:54:00 volumio sudo[11873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:54:00 volumio sudo[11873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:00 volumio sudo[11873]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:00 volumio sudo[11875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:54:00 volumio sudo[11875]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:00 volumio sudo[11875]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "services"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "alsa_controller"... Apr 18 05:54:00 volumio sudo[11885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 05:54:00 volumio sudo[11885]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:00 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "wizard"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "networkfs"... Apr 18 05:54:00 volumio volumio[11847]: info: Starting Udev Watcher for removable devices Apr 18 05:54:00 volumio volumio[11847]: info: Ignoring mount for partition: boot Apr 18 05:54:00 volumio volumio[11847]: info: Ignoring mount for partition: volumio Apr 18 05:54:00 volumio volumio[11847]: info: Ignoring mount for partition: volumio_data Apr 18 05:54:00 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "volumio_command_line_client"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "upnp"... Apr 18 05:54:00 volumio volumio[11847]: info: [1744948440261] Starting Upmpd Daemon Apr 18 05:54:00 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "my_music"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "mpd"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "upnp_browser"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "alarm-clock"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "airplay_emulation"... Apr 18 05:54:00 volumio volumio[11847]: info: Starting Shairport Sync Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "last_100"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "webradio"... Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "i2s_dacs"... Apr 18 05:54:00 volumio volumio[11847]: info: I2S DAC not set, start Auto-detection Apr 18 05:54:00 volumio volumio[11847]: info: Loading plugin "volumiodiscovery"... Apr 18 05:54:00 volumio volumio[11847]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:00 volumio volumio[11847]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:00 volumio node[11847]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:00 volumio volumio[11847]: *** WARNING *** For more information see Apr 18 05:54:00 volumio volumio[11847]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:00 volumio volumio[11847]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:00 volumio volumio[11847]: *** WARNING *** For more information see Apr 18 05:54:00 volumio node[11847]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:00 volumio node[11847]: *** WARNING *** For more information see Apr 18 05:54:00 volumio node[11847]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:00 volumio node[11847]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:00 volumio node[11847]: *** WARNING *** For more information see Apr 18 05:54:01 volumio volumio[11847]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 05:54:01 volumio volumio[11847]: info: Discovery: Started advertising with name: Volumio Apr 18 05:54:01 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:54:01 volumio volumio[11847]: info: Loading plugin "spop"... Apr 18 05:54:02 volumio volumio[11847]: info: Loading plugin "outputs"... Apr 18 05:54:02 volumio volumio[11847]: info: Loading plugin "albumart"... Apr 18 05:54:02 volumio volumio[11847]: info: Plugin example_plugin is not enabled Apr 18 05:54:02 volumio volumio[11847]: info: Loading plugin "inputs"... Apr 18 05:54:02 volumio volumio[11847]: info: Loading plugin "updater_comm"... Apr 18 05:54:02 volumio volumio[11847]: info: Plugin mpdemulation is not enabled Apr 18 05:54:02 volumio volumio[11847]: info: Loading plugin "rest_api"... Apr 18 05:54:02 volumio volumio[11847]: info: Loading plugin "websocket"... Apr 18 05:54:02 volumio volumio[11847]: info: Starting Socket.io Server version 2.3.0 Apr 18 05:54:02 volumio volumio[11847]: info: Loading plugin "fusiondsp"... Apr 18 05:54:02 volumio volumio-remote-updater[634]: [2025-04-18 03:54:02] [connect] Successful connection Apr 18 05:54:03 volumio volumio[11847]: Forking 3 albumart workers Apr 18 05:54:03 volumio volumio[11847]: info: Applying required configuration parameters for plugin fusiondsp Apr 18 05:54:03 volumio volumio[11847]: info: Loading i18n strings for locale en Apr 18 05:54:03 volumio volumio[11847]: Updating browse sources language Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::initPlayerControls Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: Express server listening on port 3000 Apr 18 05:54:03 volumio volumio[11847]: [Metrics] WebUI: 4s 804.45ms Apr 18 05:54:03 volumio volumio[11847]: info: CoreStateMachine::resetVolumioState Apr 18 05:54:03 volumio volumio[11847]: info: CoreStateMachine::getcurrentVolume Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 05:54:03 volumio volumio[11847]: info: CoreStateMachine::pushState Apr 18 05:54:03 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::volumioPushState Apr 18 05:54:03 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:03 volumio volumio[11847]: info: Volumio Network Manager: Network status updated: 1 Apr 18 05:54:03 volumio sudo[11885]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:04 volumio volumio[11847]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 18 05:54:04 volumio volumio[11847]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Apr 18 05:54:04 volumio volumio[11847]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 18 05:54:04 volumio volumio[11847]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Apr 18 05:54:04 volumio volumio-remote-updater[634]: [2025-04-18 03:54:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744948442 101 Apr 18 05:54:04 volumio volumio[11847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Apr 18 05:54:04 volumio volumio[11847]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Apr 18 05:54:04 volumio volumio[11847]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Apr 18 05:54:04 volumio volumio[11847]: Starting albumart workers Apr 18 05:54:04 volumio volumio[11847]: Starting albumart workers Apr 18 05:54:04 volumio volumio[11847]: info: Reloading queue from file Apr 18 05:54:04 volumio volumio[11847]: info: CoreStateMachine::setRepeat null single undefined Apr 18 05:54:04 volumio volumio[11847]: info: CoreStateMachine::pushState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioPushState Apr 18 05:54:04 volumio volumio[11847]: info: CoreStateMachine::setRandom null Apr 18 05:54:04 volumio volumio[11847]: info: CoreStateMachine::pushState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioPushState Apr 18 05:54:04 volumio volumio[11847]: info: Setting Device type: Raspberry PI Apr 18 05:54:04 volumio volumio[11847]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 18 05:54:04 volumio volumio[11847]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Apr 18 05:54:04 volumio volumio[11847]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 18 05:54:04 volumio volumio[11847]: info: Received Get System Info Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:54:04 volumio volumio[11847]: info: Discovery: Getting this device information Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: Starting albumart workers Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 05:54:04 volumio volumio[11847]: info: Received Get System Info Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:54:04 volumio volumio[11847]: info: Discovery: Getting this device information Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: Listing playlists Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:54:04 volumio volumio[11847]: info: Discovery: adding e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:54:04 volumio volumio[11847]: info: Discovery: Found device Volumio Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: Discovery: this is already registered, e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:54:04 volumio volumio[11847]: info: Discovery: Found device Volumio Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:04 volumio volumio[11847]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:54:04 volumio volumio[11847]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:54:04 volumio volumio[11847]: info: Completed loading Core Plugins Apr 18 05:54:04 volumio volumio[11847]: info: Preparing to generate the ALSA configuration file Apr 18 05:54:04 volumio volumio[11847]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:54:04 volumio volumio[11847]: info: Reading ALSA contributions from plugins. Apr 18 05:54:04 volumio volumio[11847]: info: FusionDsp - Apr 18 05:54:04 volumio volumio[11847]: info: FusionDsp - Apr 18 05:54:05 volumio volumio[11847]: info: FusionDsp - undefined Apr 18 05:54:05 volumio volumio[11847]: info: FusionDsp - Apr 18 05:54:05 volumio volumio[11847]: info: FusionDsp - Apr 18 05:54:05 volumio volumio[11847]: info: FusionDsp - undefined Apr 18 05:54:05 volumio volumio[11847]: info: FusionDsp - undefined Apr 18 05:54:05 volumio volumio[11847]: info: FusionDsp - undefined Apr 18 05:54:05 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 18 05:54:06 volumio volumio[11847]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:54:06 volumio volumio[11847]: info: Output device has changed, restarting MPD Apr 18 05:54:06 volumio sudo[11962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:54:06 volumio sudo[11962]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:06 volumio sudo[11962]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:06 volumio volumio[11847]: info: Output device has changed, restarting Shairport Sync Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:06 volumio sudo[11965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:54:06 volumio sudo[11965]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:06 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 05:54:06 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:54:06 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:54:06 volumio volumio[11847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:54:06 volumio volumio[11847]: info: ___________ START PLUGINS ___________ Apr 18 05:54:06 volumio volumio[11847]: info: ControllerMpd::onStart: Initializing MPD Apr 18 05:54:06 volumio volumio[11847]: info: Creating MPD Configuration file Apr 18 05:54:06 volumio sudo[11972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:54:06 volumio sudo[11972]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:54:06 volumio volumio[11847]: info: [1744948446283] CoreMusicLibrary::Adding element Media Servers Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:06 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:54:06 volumio sudo[11972]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:06 volumio sudo[11975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:54:06 volumio sudo[11975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:06 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 18 05:54:06 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:54:06 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:54:06 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:54:06 volumio volumio[11847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:54:06 volumio volumio[11847]: info: [1744948446501] CoreMusicLibrary::Adding element Last_100 Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:54:06 volumio volumio[11847]: info: [1744948446506] CoreMusicLibrary::Adding element Webradio Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:54:06 volumio volumio[11847]: info: Initializing BBC Radios Apr 18 05:54:06 volumio sudo[11978]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:54:06 volumio sudo[11978]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:06 volumio sudo[11978]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: Creating Spotify config file Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: Loading i18n strings for locale en Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 18 05:54:06 volumio volumio[11847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:06 volumio volumio[11847]: info: FusionDsp - mixtype--------------------- None Apr 18 05:54:06 volumio volumio[11847]: info: Volumio Calling Home Apr 18 05:54:06 volumio volumio[11847]: info: Preparing to generate the ALSA configuration file Apr 18 05:54:07 volumio volumio[11847]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:54:07 volumio volumio[11847]: info: Reading ALSA contributions from plugins. Apr 18 05:54:07 volumio volumio[11847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:54:07 volumio volumio[11847]: TypeError: Cannot read property 'off' of undefined Apr 18 05:54:07 volumio volumio[11847]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Apr 18 05:54:07 volumio volumio[11847]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 18 05:54:07 volumio volumio[11847]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 18 05:54:07 volumio volumio[11847]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Apr 18 05:54:07 volumio volumio[11847]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Apr 18 05:54:07 volumio volumio[11847]: at Socket.emit (events.js:315:20) Apr 18 05:54:07 volumio volumio[11847]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 18 05:54:07 volumio volumio[11847]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 18 05:54:07 volumio volumio[11847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:54:07 volumio sudo[12008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:53 Apr 18 05:54:07 volumio sudo[12008]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:07 volumio sudo[12008]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:07 volumio mpd[11986]: Apr 18 05:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 05:54:07 volumio systemd[1]: Started Music Player Daemon. Apr 18 05:54:07 volumio sudo[11965]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:07 volumio sudo[11975]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:07 volumio volumio-remote-updater[634]: [2025-04-18 03:54:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 05:54:07 volumio volumio-remote-updater[634]: [2025-04-18 03:54:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 05:54:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 05:54:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 05:54:07 volumio systemd[1]: Started dynamicswap service. Apr 18 05:54:07 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:54:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 05:54:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Apr 18 05:54:08 volumio systemd[1]: Stopped Volumio Backend Module. Apr 18 05:54:08 volumio systemd[1]: Started Volumio Backend Module. Apr 18 05:54:08 volumio systemd[1]: Started dynamicswap service. Apr 18 05:54:08 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 18 05:54:10 volumio volumio[12023]: info: ------------------------------------------- Apr 18 05:54:10 volumio volumio[12023]: info: ----- Volumio3 ---- Apr 18 05:54:10 volumio volumio[12023]: info: ------------------------------------------- Apr 18 05:54:10 volumio volumio[12023]: info: ----- System startup ---- Apr 18 05:54:10 volumio volumio[12023]: info: ------------------------------------------- Apr 18 05:54:11 volumio volumio[12023]: info: MYVOLUMIO Environment detected Apr 18 05:54:11 volumio volumio[12023]: info: Plugin folders cleanup Apr 18 05:54:11 volumio volumio[12023]: info: Scanning into folder /volumio/app/plugins/ Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category audio_interface Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category miscellanea Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category music_service Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category plugins.json Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category system_controller Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category user_interface Apr 18 05:54:11 volumio volumio[12023]: info: Scanning into folder /data/plugins/ Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category audio_interface Apr 18 05:54:11 volumio volumio[12023]: info: Scanning category music_service Apr 18 05:54:11 volumio volumio[12023]: info: Plugin folders cleanup completed Apr 18 05:54:11 volumio volumio[12023]: info: ------------------------------------------- Apr 18 05:54:11 volumio volumio[12023]: info: ----- Core plugins startup ---- Apr 18 05:54:11 volumio volumio[12023]: info: ------------------------------------------- Apr 18 05:54:11 volumio volumio[12023]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 05:54:11 volumio volumio[12023]: info: Adding plugin upnp to MyMusic Plugins Apr 18 05:54:11 volumio volumio[12023]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 05:54:11 volumio volumio[12023]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 05:54:11 volumio volumio[12023]: info: Loading plugins from folder /data/plugins/ Apr 18 05:54:11 volumio volumio[12023]: info: Loading plugin "system"... Apr 18 05:54:11 volumio volumio[12023]: info: Loading plugin "appearance"... Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "network"... Apr 18 05:54:12 volumio volumio[12023]: info: Refreshing Cached IP Addresses Apr 18 05:54:12 volumio sudo[12054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 05:54:12 volumio sudo[12054]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:12 volumio sudo[12054]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:12 volumio sudo[12056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 05:54:12 volumio sudo[12056]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:12 volumio sudo[12056]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "services"... Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "alsa_controller"... Apr 18 05:54:12 volumio sudo[12066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 05:54:12 volumio sudo[12066]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:12 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "wizard"... Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "networkfs"... Apr 18 05:54:12 volumio volumio[12023]: info: Starting Udev Watcher for removable devices Apr 18 05:54:12 volumio volumio[12023]: info: Ignoring mount for partition: boot Apr 18 05:54:12 volumio volumio[12023]: info: Ignoring mount for partition: volumio Apr 18 05:54:12 volumio volumio[12023]: info: Ignoring mount for partition: volumio_data Apr 18 05:54:12 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "volumio_command_line_client"... Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "upnp"... Apr 18 05:54:12 volumio volumio[12023]: info: [1744948452626] Starting Upmpd Daemon Apr 18 05:54:12 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "my_music"... Apr 18 05:54:12 volumio volumio[12023]: info: Loading plugin "mpd"... Apr 18 05:54:12 volumio volumio-remote-updater[634]: [2025-04-18 03:54:12] [connect] Successful connection Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "upnp_browser"... Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "alarm-clock"... Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "airplay_emulation"... Apr 18 05:54:13 volumio volumio[12023]: info: Starting Shairport Sync Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "last_100"... Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "webradio"... Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "i2s_dacs"... Apr 18 05:54:13 volumio volumio[12023]: info: I2S DAC not set, start Auto-detection Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "volumiodiscovery"... Apr 18 05:54:13 volumio volumio[12023]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:13 volumio volumio[12023]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:13 volumio node[12023]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:13 volumio volumio[12023]: *** WARNING *** For more information see Apr 18 05:54:13 volumio volumio[12023]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:13 volumio volumio[12023]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:13 volumio volumio[12023]: *** WARNING *** For more information see Apr 18 05:54:13 volumio node[12023]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:13 volumio node[12023]: *** WARNING *** For more information see Apr 18 05:54:13 volumio node[12023]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 05:54:13 volumio node[12023]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 05:54:13 volumio node[12023]: *** WARNING *** For more information see Apr 18 05:54:13 volumio volumio[12023]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 05:54:13 volumio volumio[12023]: info: Discovery: Started advertising with name: Volumio Apr 18 05:54:13 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 05:54:13 volumio volumio[12023]: info: Loading plugin "spop"... Apr 18 05:54:14 volumio volumio[12023]: info: Loading plugin "outputs"... Apr 18 05:54:14 volumio volumio[12023]: info: Loading plugin "albumart"... Apr 18 05:54:14 volumio volumio[12023]: info: Plugin example_plugin is not enabled Apr 18 05:54:14 volumio volumio[12023]: info: Loading plugin "inputs"... Apr 18 05:54:14 volumio volumio[12023]: info: Loading plugin "updater_comm"... Apr 18 05:54:15 volumio volumio[12023]: info: Plugin mpdemulation is not enabled Apr 18 05:54:15 volumio volumio[12023]: info: Loading plugin "rest_api"... Apr 18 05:54:15 volumio volumio[12023]: info: Loading plugin "websocket"... Apr 18 05:54:15 volumio volumio[12023]: info: Starting Socket.io Server version 2.3.0 Apr 18 05:54:15 volumio volumio[12023]: info: Loading plugin "fusiondsp"... Apr 18 05:54:15 volumio volumio[12023]: Forking 3 albumart workers Apr 18 05:54:15 volumio volumio[12023]: info: Applying required configuration parameters for plugin fusiondsp Apr 18 05:54:15 volumio volumio[12023]: info: Loading i18n strings for locale en Apr 18 05:54:15 volumio volumio[12023]: Updating browse sources language Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::initPlayerControls Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: Express server listening on port 3000 Apr 18 05:54:15 volumio volumio[12023]: [Metrics] WebUI: 5s 963.96ms Apr 18 05:54:15 volumio volumio[12023]: info: CoreStateMachine::resetVolumioState Apr 18 05:54:15 volumio volumio[12023]: info: CoreStateMachine::getcurrentVolume Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 05:54:15 volumio volumio[12023]: info: CoreStateMachine::pushState Apr 18 05:54:15 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::volumioPushState Apr 18 05:54:15 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:15 volumio volumio[12023]: info: Volumio Network Manager: Network status updated: 1 Apr 18 05:54:15 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 18 05:54:16 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Apr 18 05:54:16 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 18 05:54:16 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Apr 18 05:54:16 volumio sudo[12066]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:16 volumio volumio-remote-updater[634]: [2025-04-18 03:54:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744948452 101 Apr 18 05:54:16 volumio volumio[12023]: Starting albumart workers Apr 18 05:54:16 volumio volumio[12023]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Apr 18 05:54:16 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Apr 18 05:54:16 volumio volumio[12023]: Starting albumart workers Apr 18 05:54:16 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Apr 18 05:54:16 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Apr 18 05:54:16 volumio volumio[12023]: Starting albumart workers Apr 18 05:54:16 volumio volumio[12023]: info: Reloading queue from file Apr 18 05:54:16 volumio volumio[12023]: info: CoreStateMachine::setRepeat null single undefined Apr 18 05:54:16 volumio volumio[12023]: info: CoreStateMachine::pushState Apr 18 05:54:16 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::volumioPushState Apr 18 05:54:16 volumio volumio[12023]: info: CoreStateMachine::setRandom null Apr 18 05:54:16 volumio volumio[12023]: info: CoreStateMachine::pushState Apr 18 05:54:16 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::volumioPushState Apr 18 05:54:16 volumio volumio[12023]: info: Setting Device type: Raspberry PI Apr 18 05:54:16 volumio volumio[12023]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 18 05:54:16 volumio volumio[12023]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Apr 18 05:54:16 volumio volumio[12023]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 18 05:54:16 volumio volumio[12023]: info: Completed loading Core Plugins Apr 18 05:54:16 volumio volumio[12023]: info: Preparing to generate the ALSA configuration file Apr 18 05:54:16 volumio volumio[12023]: info: Discovery: adding e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:54:16 volumio volumio[12023]: info: Discovery: Found device Volumio Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:16 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:16 volumio volumio[12023]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:54:16 volumio volumio[12023]: info: Reading ALSA contributions from plugins. Apr 18 05:54:16 volumio volumio[12023]: info: Discovery: this is already registered, e0e16d1d-f96c-4c21-9d63-53fb16f43aad Apr 18 05:54:16 volumio volumio[12023]: info: Discovery: Found device Volumio Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:16 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:16 volumio volumio[12023]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:54:16 volumio volumio[12023]: info: Output device has changed, restarting MPD Apr 18 05:54:16 volumio volumio[12023]: info: Output device has changed, restarting Shairport Sync Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:16 volumio sudo[12119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:54:16 volumio sudo[12119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:16 volumio sudo[12119]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:16 volumio sudo[12121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:54:16 volumio sudo[12121]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:16 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 05:54:16 volumio volumio[12023]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:54:16 volumio volumio[12023]: info: ___________ START PLUGINS ___________ Apr 18 05:54:16 volumio volumio[12023]: info: ControllerMpd::onStart: Initializing MPD Apr 18 05:54:16 volumio volumio[12023]: info: Creating MPD Configuration file Apr 18 05:54:16 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:54:16 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:54:16 volumio sudo[12128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:54:16 volumio sudo[12128]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:16 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:54:16 volumio sudo[12128]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:54:16 volumio volumio[12023]: info: [1744948456780] CoreMusicLibrary::Adding element Media Servers Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:16 volumio sudo[12132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:54:16 volumio sudo[12132]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 18 05:54:16 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:54:16 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:16 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:54:16 volumio volumio[12023]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:16 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:17 volumio sudo[12134]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:54:17 volumio sudo[12134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:17 volumio sudo[12134]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:17 volumio volumio[12023]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:54:17 volumio volumio[12023]: info: [1744948457090] CoreMusicLibrary::Adding element Last_100 Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 05:54:17 volumio volumio[12023]: info: [1744948457096] CoreMusicLibrary::Adding element Webradio Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:54:17 volumio volumio[12023]: info: Initializing BBC Radios Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:17 volumio volumio[12023]: info: Creating Spotify config file Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:17 volumio volumio[12023]: info: Loading i18n strings for locale en Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 18 05:54:17 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:17 volumio volumio[12023]: info: FusionDsp - mixtype--------------------- None Apr 18 05:54:17 volumio volumio[12023]: info: Volumio Calling Home Apr 18 05:54:17 volumio volumio[12023]: info: Preparing to generate the ALSA configuration file Apr 18 05:54:17 volumio volumio[12023]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 05:54:17 volumio volumio[12023]: info: Reading ALSA contributions from plugins. Apr 18 05:54:17 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Apr 18 05:54:17 volumio volumio[12023]: verbose: New Socket.io Connection to 192.168.22.122 from 192.168.22.110 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Apr 18 05:54:17 volumio volumio[12023]: info: MPD Permissions set Apr 18 05:54:17 volumio volumio[12023]: info: MPD Permissions set Apr 18 05:54:17 volumio volumio[12023]: info: Volumio called home Apr 18 05:54:17 volumio volumio[12023]: info: Spotify config file written Apr 18 05:54:17 volumio sudo[12181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 05:54:17 volumio sudo[12181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:17 volumio systemd[1]: Stopping go-librespot Daemon... Apr 18 05:54:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 18 05:54:17 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 18 05:54:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 05:54:18 volumio systemd[1]: Started go-librespot Daemon. Apr 18 05:54:18 volumio go-librespot[12183]: go-librespot daemon starting... Apr 18 05:54:18 volumio sudo[12181]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:18 volumio volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=info msg="running go-librespot 0.2.0" Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=debug msg="app state loaded" Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=debug msg="stored credentials not found" Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: No need to fix Spotify hosts Apr 18 05:54:18 volumio volumio[12023]: info: Received Get System Info Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:54:18 volumio volumio[12023]: info: Discovery: Getting this device information Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:18 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:18 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:18 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 05:54:18 volumio volumio[12023]: info: Received Get System Info Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 05:54:18 volumio volumio[12023]: info: Discovery: Getting this device information Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:18 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:18 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:18 volumio volumio[12023]: info: Listing playlists Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 18 05:54:18 volumio go-librespot[12183]: time="2025-04-18T05:54:18+02:00" level=info msg="zeroconf server listening on port 36155" Apr 18 05:54:18 volumio volumio[12023]: info: Starting Shairport Sync Apr 18 05:54:18 volumio volumio[12023]: info: Starting Shairport Sync Apr 18 05:54:18 volumio sudo[12201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:54:18 volumio sudo[12201]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:18 volumio volumio[12023]: info: Starting Shairport Sync Apr 18 05:54:18 volumio sudo[12203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:54:18 volumio sudo[12203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::volumioGetState Apr 18 05:54:18 volumio volumio[12023]: info: CorePlayQueue::getTrack 0 Apr 18 05:54:18 volumio sudo[12206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 05:54:18 volumio sudo[12206]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:54:18 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:54:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 05:54:18 volumio volumio[12023]: info: Asound.conf file unchanged, so no further update is needed Apr 18 05:54:18 volumio volumio[12023]: info: Output device has changed, restarting MPD Apr 18 05:54:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:54:18 volumio sudo[12201]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:18 volumio sudo[12203]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 05:54:18 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 18 05:54:18 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 05:54:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 05:54:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 05:54:18 volumio volumio[12023]: info: Output device has changed, restarting Shairport Sync Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:18 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 05:54:18 volumio sudo[12211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 05:54:18 volumio sudo[12206]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:18 volumio sudo[12211]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:18 volumio sudo[12211]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:18 volumio sudo[12214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 05:54:18 volumio sudo[12214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 18 05:54:18 volumio systemd[1]: mpd.service: Succeeded. Apr 18 05:54:18 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 05:54:18 volumio volumio[12023]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 05:54:18 volumio systemd[1]: Starting Music Player Daemon... Apr 18 05:54:18 volumio volumio[12023]: info: MPD Permissions set Apr 18 05:54:18 volumio volumio[12023]: info: Shairport-Sync Started Apr 18 05:54:18 volumio volumio[12023]: Error adding Membership: Error: addMembership EINVAL Apr 18 05:54:18 volumio volumio[12023]: info: Shairport-Sync Started Apr 18 05:54:18 volumio volumio[12023]: info: Shairport-Sync Started Apr 18 05:54:18 volumio volumio[12023]: info: FusionDsp - Apr 18 05:54:18 volumio sudo[12222]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 05:54:18 volumio sudo[12222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:18 volumio sudo[12222]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:18 volumio volumio[12023]: info: FusionDsp - Apr 18 05:54:19 volumio volumio[12023]: info: FusionDsp - Apr 18 05:54:19 volumio volumio[12023]: info: FusionDsp - Apr 18 05:54:19 volumio volumio[12023]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:54:19 volumio volumio[12023]: 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 18 05:54:19 volumio volumio[12023]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 18 05:54:19 volumio volumio[12023]: info: camilladsp service started and running in background, instance 1 Apr 18 05:54:19 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 05:54:19 volumio volumio[12023]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:54:19 volumio volumio[12023]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 18 05:54:19 volumio volumio[12023]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 18 05:54:19 volumio volumio[12023]: info: FusionDsp loaded Apr 18 05:54:19 volumio volumio[12023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 05:54:19 volumio sudo[12237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 18 05:54:19 volumio sudo[12237]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 05:54:19 volumio volumio[12023]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 18 05:54:19 volumio volumio[12023]: info: Adding Signal Path Element [object Object] Apr 18 05:54:19 volumio volumio[12023]: info: Adding fusiondspeq DSP Signal Path Element Apr 18 05:54:19 volumio volumio[12023]: info: FusionDsp - ---- installed callbackRead Apr 18 05:54:19 volumio volumio[12023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:54:19 volumio sudo[12237]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:19 volumio volumio[12023]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 18 05:54:19 volumio volumio[12023]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 18 05:54:19 volumio volumio[12023]: at onErrorNT (internal/child_process.js:465:16) Apr 18 05:54:19 volumio volumio[12023]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 05:54:19 volumio volumio[12023]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 18 05:54:19 volumio volumio[12023]: at listOnTimeout (internal/timers.js:523:9) Apr 18 05:54:19 volumio volumio[12023]: at processTimers (internal/timers.js:497:7) { Apr 18 05:54:19 volumio volumio[12023]: errno: -2, Apr 18 05:54:19 volumio volumio[12023]: code: 'ENOENT', Apr 18 05:54:19 volumio volumio[12023]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:54:19 volumio volumio[12023]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 18 05:54:19 volumio volumio[12023]: spawnargs: [ Apr 18 05:54:19 volumio volumio[12023]: '-p', Apr 18 05:54:19 volumio volumio[12023]: 9876, Apr 18 05:54:19 volumio volumio[12023]: '-o', Apr 18 05:54:19 volumio volumio[12023]: '/tmp/camilladsp.log', Apr 18 05:54:19 volumio volumio[12023]: '-l', Apr 18 05:54:19 volumio volumio[12023]: 'warn', Apr 18 05:54:19 volumio volumio[12023]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 18 05:54:19 volumio volumio[12023]: ] Apr 18 05:54:19 volumio volumio[12023]: } Apr 18 05:54:19 volumio volumio[12023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 05:54:20 volumio mpd[12225]: Apr 18 05:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 05:54:20 volumio systemd[1]: Started Music Player Daemon. Apr 18 05:54:20 volumio sudo[12132]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:20 volumio sudo[12214]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:20 volumio sudo[12121]: pam_unix(sudo:session): session closed for user root Apr 18 05:54:20 volumio sudo[12250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 05:53 Apr 18 05:54:20 volumio sudo[12250]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"