-- Logs begin at Fri 2024-11-29 23:17:57 GMT, end at Fri 2024-11-29 23:36:12 GMT. -- Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "outputs"... Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "albumart"... Nov 29 23:35:00 volumio-2 volumio[29419]: info: Plugin example_plugin is not enabled Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "inputs"... Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "updater_comm"... Nov 29 23:35:00 volumio-2 volumio[29419]: info: Plugin mpdemulation is not enabled Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "rest_api"... Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "websocket"... Nov 29 23:35:00 volumio-2 volumio[29419]: info: Starting Socket.io Server version 2.3.0 Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "fusiondsp"... Nov 29 23:35:00 volumio-2 volumio[29419]: Forking 3 albumart workers Nov 29 23:35:00 volumio-2 volumio[29419]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading plugin "radio_paradise"... Nov 29 23:35:00 volumio-2 volumio[29419]: info: Applying required configuration parameters for plugin radio_paradise Nov 29 23:35:00 volumio-2 volumio[29419]: info: [1732923300878] [RadioParadise] API delay: 5 Nov 29 23:35:00 volumio-2 volumio[29419]: info: Loading i18n strings for locale en Nov 29 23:35:00 volumio-2 volumio[29419]: Updating browse sources language Nov 29 23:35:00 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::initPlayerControls Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: Express server listening on port 3000 Nov 29 23:35:01 volumio-2 volumio[29419]: [Metrics] WebUI: 5s 491.23ms Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::resetVolumioState Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::getcurrentVolume Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:01 volumio-2 volumio[29419]: info: Volumio Network Manager: Network status updated: 1 Nov 29 23:35:01 volumio-2 volumio[29419]: Starting albumart workers Nov 29 23:35:01 volumio-2 volumio[29419]: Starting albumart workers Nov 29 23:35:01 volumio-2 volumio[29419]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 29 23:35:01 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732923299 101 Nov 29 23:35:01 volumio-2 volumio[29419]: 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: 2 Nov 29 23:35:01 volumio-2 volumio[29419]: Starting albumart workers Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: Reloading queue from file Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::setRepeat null single undefined Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::pushState Nov 29 23:35:01 volumio-2 volumio[29419]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::setRandom null Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::pushState Nov 29 23:35:01 volumio-2 volumio[29419]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:01 volumio-2 volumio[29419]: info: Setting Device type: Raspberry PI Nov 29 23:35:01 volumio-2 volumio[29419]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::pushState Nov 29 23:35:01 volumio-2 volumio[29419]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreStateMachine::updateTrackBlock Nov 29 23:35:01 volumio-2 volumio[29419]: info: CorePlayQueue::getTrackBlock Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:01 volumio-2 volumio[29419]: info: Completed loading Core Plugins Nov 29 23:35:01 volumio-2 volumio[29419]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:01 volumio-2 volumio[29419]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:01 volumio-2 volumio[29419]: info: Reading ALSA contributions from plugins. Nov 29 23:35:01 volumio-2 volumio[29419]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:01 volumio-2 volumio[29419]: info: Output device has changed, restarting MPD Nov 29 23:35:01 volumio-2 sudo[29512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:01 volumio-2 volumio[29419]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:01 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:01 volumio-2 sudo[29512]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:01 volumio-2 sudo[29512]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:01 volumio-2 sudo[29514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:01 volumio-2 sudo[29514]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:01 volumio-2 volumio[29419]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:01 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 29 23:35:01 volumio-2 volumio[29419]: info: ___________ START PLUGINS ___________ Nov 29 23:35:02 volumio-2 volumio[29419]: info: ControllerMpd::onStart: Initializing MPD Nov 29 23:35:02 volumio-2 volumio[29419]: info: Creating MPD Configuration file Nov 29 23:35:02 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:02 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:02 volumio-2 volumio[29419]: info: [1732923302077] CoreMusicLibrary::Adding element Media Servers Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:02 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:02 volumio-2 sudo[29532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:02 volumio-2 sudo[29532]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:02 volumio-2 sudo[29530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:02 volumio-2 sudo[29530]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:02 volumio-2 sudo[29530]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:02 volumio-2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 29 23:35:02 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:02 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:02 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:02 volumio-2 volumio[29419]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:02 volumio-2 sudo[29535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:02 volumio-2 sudo[29535]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:02 volumio-2 sudo[29535]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:02 volumio-2 volumio[29419]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:02 volumio-2 volumio[29419]: info: [1732923302312] CoreMusicLibrary::Adding element Last_100 Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:02 volumio-2 volumio[29419]: info: [1732923302316] CoreMusicLibrary::Adding element Webradio Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:02 volumio-2 volumio[29419]: info: Initializing BBC Radios Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: Creating Spotify config file Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: Loading i18n strings for locale en Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:02 volumio-2 volumio[29419]: info: [1732923302449] CoreMusicLibrary::Adding element Radio Paradise Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:02 volumio-2 volumio[29419]: Cannot find translation for source Radio Paradise Nov 29 23:35:02 volumio-2 volumio[29419]: info: Volumio Calling Home Nov 29 23:35:02 volumio-2 volumio[29419]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:02 volumio-2 volumio[29419]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:02 volumio-2 volumio[29419]: info: Reading ALSA contributions from plugins. Nov 29 23:35:02 volumio-2 volumio[29419]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 29 23:35:02 volumio-2 volumio[29419]: info: MPD Permissions set Nov 29 23:35:02 volumio-2 volumio[29419]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreStateMachine::pushState Nov 29 23:35:02 volumio-2 volumio[29419]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:02 volumio-2 volumio[29419]: info: Discovery: adding 73caacf9-cf0b-4e00-807c-e3f5ec7e4332 Nov 29 23:35:02 volumio-2 volumio[29419]: info: Discovery: Found device Volumio 2 Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:02 volumio-2 volumio[29419]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:02 volumio-2 volumio[29419]: info: Volumio called home Nov 29 23:35:02 volumio-2 volumio[29419]: info: Spotify config file written Nov 29 23:35:02 volumio-2 volumio[29419]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 sudo[29574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 23:35:02 volumio-2 sudo[29574]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 systemd[1]: Stopping go-librespot Daemon... Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:02 volumio-2 systemd[1]: go-librespot-daemon.service: Succeeded. Nov 29 23:35:02 volumio-2 systemd[1]: Stopped go-librespot Daemon. Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:02 volumio-2 volumio[29419]: info: No need to fix Spotify hosts Nov 29 23:35:02 volumio-2 systemd[1]: Started go-librespot Daemon. Nov 29 23:35:02 volumio-2 go-librespot[29581]: Librespot-go daemon starting... Nov 29 23:35:02 volumio-2 sudo[29574]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:02 volumio-2 go-librespot[29581]: time="2024-11-29T23:35:02Z" level=info msg="generated new device id: 477001a4371935ccaeea07dbbef987b684f0fc95" Nov 29 23:35:03 volumio-2 volumio[29419]: info: Starting Shairport Sync Nov 29 23:35:03 volumio-2 volumio[29419]: info: Starting Shairport Sync Nov 29 23:35:03 volumio-2 sudo[29592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:03 volumio-2 sudo[29592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:03 volumio-2 volumio[29419]: info: Starting Shairport Sync Nov 29 23:35:03 volumio-2 sudo[29597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:03 volumio-2 sudo[29597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:03 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:03 volumio-2 volumio[29419]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:03 volumio-2 volumio[29419]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:03 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:03 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:03 volumio-2 sudo[29601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:03 volumio-2 sudo[29601]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:03 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:03 volumio-2 volumio[29419]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:03 volumio-2 volumio[29419]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:03 volumio-2 go-librespot[29581]: time="2024-11-29T23:35:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 29 23:35:03 volumio-2 go-librespot[29581]: time="2024-11-29T23:35:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 29 23:35:03 volumio-2 go-librespot[29581]: time="2024-11-29T23:35:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 29 23:35:03 volumio-2 go-librespot[29581]: time="2024-11-29T23:35:03Z" level=debug msg="zeroconf server listening on port 38447" Nov 29 23:35:03 volumio-2 volumio[29419]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:03 volumio-2 volumio[29419]: info: Output device has changed, restarting MPD Nov 29 23:35:03 volumio-2 sudo[29592]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:03 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:03 volumio-2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:03 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:03 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:03 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:03 volumio-2 sudo[29606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:03 volumio-2 sudo[29606]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:03 volumio-2 sudo[29601]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:03 volumio-2 volumio[29419]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:03 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:03 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:03 volumio-2 sudo[29597]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:03 volumio-2 sudo[29606]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:03 volumio-2 sudo[29610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:03 volumio-2 sudo[29610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:03 volumio-2 volumio[29419]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:03 volumio-2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:03 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:03 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:03 volumio-2 volumio[29419]: info: MPD Permissions set Nov 29 23:35:03 volumio-2 volumio[29419]: info: Shairport-Sync Started Nov 29 23:35:03 volumio-2 volumio[29419]: Error adding Membership: Error: addMembership EINVAL Nov 29 23:35:03 volumio-2 volumio[29419]: info: Shairport-Sync Started Nov 29 23:35:03 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:03 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:03 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:03 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:03 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:03 volumio-2 volumio[29419]: info: Starting Shairport Sync Nov 29 23:35:03 volumio-2 volumio[29419]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 29 23:35:03 volumio-2 volumio[29419]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:03 volumio-2 sudo[29624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:03 volumio-2 sudo[29624]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:03 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:03 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:03 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:03 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:03 volumio-2 sudo[29617]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:03 volumio-2 sudo[29617]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:03 volumio-2 sudo[29624]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:03 volumio-2 volumio[29419]: info: Shairport-Sync Started Nov 29 23:35:03 volumio-2 sudo[29617]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:04 volumio-2 volumio[29419]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:04 volumio-2 volumio[29419]: 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 Nov 29 23:35:04 volumio-2 volumio[29419]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:04 volumio-2 volumio[29419]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 29 23:35:04 volumio-2 volumio[29419]: info: camilladsp service started and running in background, instance 1 Nov 29 23:35:04 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:04 volumio-2 volumio[29419]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:04 volumio-2 volumio[29419]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 29 23:35:04 volumio-2 volumio[29419]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:04 volumio-2 volumio[29419]: info: FusionDsp loaded Nov 29 23:35:04 volumio-2 volumio[29419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:04 volumio-2 sudo[29642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 29 23:35:04 volumio-2 sudo[29642]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:04 volumio-2 volumio[29419]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 29 23:35:04 volumio-2 volumio[29419]: info: Adding Signal Path Element [object Object] Nov 29 23:35:04 volumio-2 volumio[29419]: info: Adding fusiondspeq DSP Signal Path Element Nov 29 23:35:04 volumio-2 volumio[29419]: info: FusionDsp - ---- installed callbackRead Nov 29 23:35:04 volumio-2 volumio[29419]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:04 volumio-2 sudo[29642]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:04 volumio-2 volumio[29419]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 29 23:35:04 volumio-2 volumio[29419]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 29 23:35:04 volumio-2 volumio[29419]: at onErrorNT (internal/child_process.js:465:16) Nov 29 23:35:04 volumio-2 volumio[29419]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 29 23:35:04 volumio-2 volumio[29419]: errno: -13, Nov 29 23:35:04 volumio-2 volumio[29419]: code: 'EACCES', Nov 29 23:35:04 volumio-2 volumio[29419]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:04 volumio-2 volumio[29419]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:04 volumio-2 volumio[29419]: spawnargs: [ Nov 29 23:35:04 volumio-2 volumio[29419]: '-p', Nov 29 23:35:04 volumio-2 volumio[29419]: 9876, Nov 29 23:35:04 volumio-2 volumio[29419]: '-o', Nov 29 23:35:04 volumio-2 volumio[29419]: '/tmp/camilladsp.log', Nov 29 23:35:04 volumio-2 volumio[29419]: '-l', Nov 29 23:35:04 volumio-2 volumio[29419]: 'warn', Nov 29 23:35:04 volumio-2 volumio[29419]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 29 23:35:04 volumio-2 volumio[29419]: ] Nov 29 23:35:04 volumio-2 volumio[29419]: } Nov 29 23:35:04 volumio-2 volumio[29419]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:04 volumio-2 mpd[29630]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 23:35:04 volumio-2 mpd[29630]: output: No 'audio_output' defined in config file Nov 29 23:35:04 volumio-2 mpd[29630]: output: Successfully detected a sndio audio device Nov 29 23:35:04 volumio-2 mpd[29630]: zeroconf: No global port, disabling zeroconf Nov 29 23:35:04 volumio-2 systemd[1]: Started Music Player Daemon. Nov 29 23:35:04 volumio-2 sudo[29610]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:04 volumio-2 sudo[29514]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:04 volumio-2 sudo[29532]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:05 volumio-2 sudo[29656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 23:34 Nov 29 23:35:05 volumio-2 sudo[29656]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:05 volumio-2 sudo[29656]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:05 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 23:35:05 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 23:35:05 volumio-2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 29 23:35:05 volumio-2 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 29 23:35:05 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:05 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:05 volumio-2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 29 23:35:05 volumio-2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 113. Nov 29 23:35:05 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:05 volumio-2 systemd[1]: Stopped Volumio Backend Module. Nov 29 23:35:05 volumio-2 systemd[1]: Started Volumio Backend Module. Nov 29 23:35:05 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:07 volumio-2 volumio[29667]: info: ------------------------------------------- Nov 29 23:35:07 volumio-2 volumio[29667]: info: ----- Volumio3 ---- Nov 29 23:35:07 volumio-2 volumio[29667]: info: ------------------------------------------- Nov 29 23:35:07 volumio-2 volumio[29667]: info: ----- System startup ---- Nov 29 23:35:07 volumio-2 volumio[29667]: info: ------------------------------------------- Nov 29 23:35:08 volumio-2 volumio[29667]: info: MYVOLUMIO Environment detected Nov 29 23:35:08 volumio-2 volumio[29667]: info: Plugin folders cleanup Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning into folder /volumio/app/plugins/ Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category audio_interface Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category miscellanea Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category music_service Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category plugins.json Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category system_controller Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category user_interface Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning into folder /data/plugins/ Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category audio_interface Nov 29 23:35:08 volumio-2 volumio[29667]: info: Scanning category music_service Nov 29 23:35:08 volumio-2 volumio[29667]: info: Plugin folders cleanup completed Nov 29 23:35:08 volumio-2 volumio[29667]: info: ------------------------------------------- Nov 29 23:35:08 volumio-2 volumio[29667]: info: ----- Core plugins startup ---- Nov 29 23:35:08 volumio-2 volumio[29667]: info: ------------------------------------------- Nov 29 23:35:08 volumio-2 volumio[29667]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 23:35:08 volumio-2 volumio[29667]: info: Adding plugin upnp to MyMusic Plugins Nov 29 23:35:08 volumio-2 volumio[29667]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 23:35:08 volumio-2 volumio[29667]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 23:35:08 volumio-2 volumio[29667]: info: Loading plugins from folder /data/plugins/ Nov 29 23:35:08 volumio-2 volumio[29667]: info: Loading plugin "system"... Nov 29 23:35:08 volumio-2 volumio[29667]: info: Loading plugin "appearance"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "network"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Refreshing Cached IP Addresses Nov 29 23:35:09 volumio-2 sudo[29693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 23:35:09 volumio-2 sudo[29693]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:09 volumio-2 sudo[29693]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:09 volumio-2 sudo[29695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "services"... Nov 29 23:35:09 volumio-2 sudo[29695]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "alsa_controller"... Nov 29 23:35:09 volumio-2 sudo[29695]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:09 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "wizard"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "networkfs"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Starting Udev Watcher for removable devices Nov 29 23:35:09 volumio-2 volumio[29667]: info: Ignoring mount for partition: boot Nov 29 23:35:09 volumio-2 volumio[29667]: info: Ignoring mount for partition: volumio Nov 29 23:35:09 volumio-2 volumio[29667]: info: Ignoring mount for partition: volumio_data Nov 29 23:35:09 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "volumio_command_line_client"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "upnp"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: [1732923309319] Starting Upmpd Daemon Nov 29 23:35:09 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "my_music"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "mpd"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "upnp_browser"... Nov 29 23:35:09 volumio-2 volumio[29667]: info: Loading plugin "alarm-clock"... Nov 29 23:35:10 volumio-2 volumio[29667]: info: Loading plugin "airplay_emulation"... Nov 29 23:35:10 volumio-2 volumio[29667]: info: Starting Shairport Sync Nov 29 23:35:10 volumio-2 volumio[29667]: info: Loading plugin "last_100"... Nov 29 23:35:10 volumio-2 volumio[29667]: info: Loading plugin "webradio"... Nov 29 23:35:10 volumio-2 volumio[29667]: info: Loading plugin "i2s_dacs"... Nov 29 23:35:10 volumio-2 volumio[29667]: info: Loading plugin "volumiodiscovery"... Nov 29 23:35:10 volumio-2 volumio[29667]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:10 volumio-2 volumio[29667]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:10 volumio-2 volumio[29667]: *** WARNING *** For more information see Nov 29 23:35:10 volumio-2 volumio[29667]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:10 volumio-2 node[29667]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:10 volumio-2 volumio[29667]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:10 volumio-2 volumio[29667]: *** WARNING *** For more information see Nov 29 23:35:10 volumio-2 node[29667]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:10 volumio-2 node[29667]: *** WARNING *** For more information see Nov 29 23:35:10 volumio-2 node[29667]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:10 volumio-2 node[29667]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:10 volumio-2 node[29667]: *** WARNING *** For more information see Nov 29 23:35:10 volumio-2 volumio[29667]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 23:35:10 volumio-2 volumio[29667]: info: Discovery: Started advertising with name: Volumio 2 Nov 29 23:35:10 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:10 volumio-2 volumio[29667]: info: Loading plugin "spop"... Nov 29 23:35:10 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:10] [connect] Successful connection Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "outputs"... Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "albumart"... Nov 29 23:35:11 volumio-2 volumio[29667]: info: Plugin example_plugin is not enabled Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "inputs"... Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "updater_comm"... Nov 29 23:35:11 volumio-2 volumio[29667]: info: Plugin mpdemulation is not enabled Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "rest_api"... Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "websocket"... Nov 29 23:35:11 volumio-2 volumio[29667]: info: Starting Socket.io Server version 2.3.0 Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "fusiondsp"... Nov 29 23:35:11 volumio-2 volumio[29667]: Forking 3 albumart workers Nov 29 23:35:11 volumio-2 volumio[29667]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 23:35:11 volumio-2 volumio[29667]: info: Loading plugin "radio_paradise"... Nov 29 23:35:12 volumio-2 volumio[29667]: info: Applying required configuration parameters for plugin radio_paradise Nov 29 23:35:12 volumio-2 volumio[29667]: info: [1732923312137] [RadioParadise] API delay: 5 Nov 29 23:35:12 volumio-2 volumio[29667]: info: Loading i18n strings for locale en Nov 29 23:35:12 volumio-2 volumio[29667]: Updating browse sources language Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::initPlayerControls Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: Express server listening on port 3000 Nov 29 23:35:12 volumio-2 volumio[29667]: [Metrics] WebUI: 5s 516.11ms Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::resetVolumioState Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::getcurrentVolume Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:12 volumio-2 volumio[29667]: info: Volumio Network Manager: Network status updated: 1 Nov 29 23:35:12 volumio-2 volumio[29667]: Starting albumart workers Nov 29 23:35:12 volumio-2 volumio[29667]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 29 23:35:12 volumio-2 volumio[29667]: Starting albumart workers Nov 29 23:35:12 volumio-2 volumio[29667]: Starting albumart workers Nov 29 23:35:12 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732923310 101 Nov 29 23:35:12 volumio-2 volumio[29667]: 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: 2 Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:12 volumio-2 volumio[29667]: info: Reloading queue from file Nov 29 23:35:12 volumio-2 volumio[29667]: info: Setting Device type: Raspberry PI Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::setRepeat null single undefined Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::pushState Nov 29 23:35:12 volumio-2 volumio[29667]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::setRandom null Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::pushState Nov 29 23:35:12 volumio-2 volumio[29667]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:12 volumio-2 volumio[29667]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::pushState Nov 29 23:35:12 volumio-2 volumio[29667]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreStateMachine::updateTrackBlock Nov 29 23:35:12 volumio-2 volumio[29667]: info: CorePlayQueue::getTrackBlock Nov 29 23:35:12 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:13 volumio-2 volumio[29667]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 29 23:35:13 volumio-2 volumio[29667]: info: Completed loading Core Plugins Nov 29 23:35:13 volumio-2 volumio[29667]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:13 volumio-2 volumio[29667]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:13 volumio-2 volumio[29667]: info: Reading ALSA contributions from plugins. Nov 29 23:35:13 volumio-2 volumio[29667]: info: Discovery: adding 73caacf9-cf0b-4e00-807c-e3f5ec7e4332 Nov 29 23:35:13 volumio-2 volumio[29667]: info: Discovery: Found device Volumio 2 Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:13 volumio-2 volumio[29667]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:13 volumio-2 volumio[29667]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreStateMachine::pushState Nov 29 23:35:13 volumio-2 volumio[29667]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:13 volumio-2 volumio[29667]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:13 volumio-2 volumio[29667]: info: Output device has changed, restarting MPD Nov 29 23:35:13 volumio-2 volumio[29667]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:13 volumio-2 sudo[29761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:13 volumio-2 sudo[29761]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:13 volumio-2 sudo[29761]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:13 volumio-2 sudo[29762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:13 volumio-2 sudo[29762]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:13 volumio-2 volumio[29667]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:13 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 29 23:35:13 volumio-2 volumio[29667]: info: ___________ START PLUGINS ___________ Nov 29 23:35:13 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:13 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:13 volumio-2 volumio[29667]: info: ControllerMpd::onStart: Initializing MPD Nov 29 23:35:13 volumio-2 volumio[29667]: info: Creating MPD Configuration file Nov 29 23:35:13 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:13 volumio-2 sudo[29794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:13 volumio-2 sudo[29794]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:13 volumio-2 sudo[29794]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:13 volumio-2 volumio[29667]: info: [1732923313501] CoreMusicLibrary::Adding element Media Servers Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:13 volumio-2 sudo[29797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:13 volumio-2 sudo[29797]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:13 volumio-2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 29 23:35:13 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:13 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:13 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:13 volumio-2 volumio[29667]: info: [1732923313661] CoreMusicLibrary::Adding element Last_100 Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:13 volumio-2 sudo[29799]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:13 volumio-2 sudo[29799]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:13 volumio-2 volumio[29667]: info: [1732923313668] CoreMusicLibrary::Adding element Webradio Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:13 volumio-2 sudo[29799]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:13 volumio-2 volumio[29667]: info: Initializing BBC Radios Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: Creating Spotify config file Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: Loading i18n strings for locale en Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:13 volumio-2 volumio[29667]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:13 volumio-2 volumio[29667]: info: [1732923313794] CoreMusicLibrary::Adding element Radio Paradise Nov 29 23:35:13 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:13 volumio-2 volumio[29667]: Cannot find translation for source Radio Paradise Nov 29 23:35:13 volumio-2 volumio[29667]: info: Volumio Calling Home Nov 29 23:35:13 volumio-2 volumio[29667]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:14 volumio-2 volumio[29667]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:14 volumio-2 volumio[29667]: info: Reading ALSA contributions from plugins. Nov 29 23:35:14 volumio-2 volumio[29667]: info: MPD Permissions set Nov 29 23:35:14 volumio-2 volumio[29667]: info: MPD Permissions set Nov 29 23:35:14 volumio-2 volumio[29667]: info: Spotify config file written Nov 29 23:35:14 volumio-2 sudo[29822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 23:35:14 volumio-2 volumio[29667]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 29 23:35:14 volumio-2 sudo[29822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 volumio[29667]: info: Volumio called home Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 systemd[1]: Stopping go-librespot Daemon... Nov 29 23:35:14 volumio-2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:14 volumio-2 systemd[1]: go-librespot-daemon.service: Succeeded. Nov 29 23:35:14 volumio-2 systemd[1]: Stopped go-librespot Daemon. Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: No need to fix Spotify hosts Nov 29 23:35:14 volumio-2 systemd[1]: Started go-librespot Daemon. Nov 29 23:35:14 volumio-2 go-librespot[29828]: Librespot-go daemon starting... Nov 29 23:35:14 volumio-2 sudo[29822]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:14 volumio-2 go-librespot[29828]: time="2024-11-29T23:35:14Z" level=info msg="generated new device id: e6546c9fcac3b5aed869f983c1d491d455211e8d" Nov 29 23:35:14 volumio-2 volumio[29667]: info: Starting Shairport Sync Nov 29 23:35:14 volumio-2 volumio[29667]: info: Starting Shairport Sync Nov 29 23:35:14 volumio-2 sudo[29841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:14 volumio-2 volumio[29667]: info: Starting Shairport Sync Nov 29 23:35:14 volumio-2 sudo[29841]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 sudo[29843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:14 volumio-2 sudo[29843]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 volumio[29667]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:14 volumio-2 volumio[29667]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:14 volumio-2 sudo[29847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:14 volumio-2 sudo[29847]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:14 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:14 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:14 volumio-2 volumio[29667]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:14 volumio-2 volumio[29667]: info: Output device has changed, restarting MPD Nov 29 23:35:14 volumio-2 go-librespot[29828]: time="2024-11-29T23:35:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 29 23:35:14 volumio-2 go-librespot[29828]: time="2024-11-29T23:35:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 29 23:35:14 volumio-2 go-librespot[29828]: time="2024-11-29T23:35:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 29 23:35:14 volumio-2 go-librespot[29828]: time="2024-11-29T23:35:14Z" level=debug msg="zeroconf server listening on port 40651" Nov 29 23:35:14 volumio-2 sudo[29853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:14 volumio-2 sudo[29853]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 volumio[29667]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:14 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:14 volumio-2 sudo[29843]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:14 volumio-2 sudo[29853]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:14 volumio-2 sudo[29841]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:14 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:14 volumio-2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:14 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:14 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:14 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:14 volumio-2 sudo[29847]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:14 volumio-2 sudo[29855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:14 volumio-2 sudo[29855]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 volumio[29667]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:14 volumio-2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:14 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:14 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:14 volumio-2 volumio[29667]: info: MPD Permissions set Nov 29 23:35:14 volumio-2 volumio[29667]: info: Shairport-Sync Started Nov 29 23:35:14 volumio-2 volumio[29667]: Error adding Membership: Error: addMembership EINVAL Nov 29 23:35:14 volumio-2 volumio[29667]: info: Shairport-Sync Started Nov 29 23:35:14 volumio-2 volumio[29667]: info: Shairport-Sync Started Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:14 volumio-2 volumio[29667]: info: Starting Shairport Sync Nov 29 23:35:14 volumio-2 volumio[29667]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:14 volumio-2 sudo[29871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:14 volumio-2 sudo[29871]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:14 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:14 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:14 volumio-2 volumio[29667]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 29 23:35:14 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:14 volumio-2 sudo[29864]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:14 volumio-2 sudo[29864]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:14 volumio-2 sudo[29871]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:14 volumio-2 sudo[29864]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:14 volumio-2 volumio[29667]: info: Shairport-Sync Started Nov 29 23:35:15 volumio-2 volumio[29667]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:15 volumio-2 volumio[29667]: 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 Nov 29 23:35:15 volumio-2 volumio[29667]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:15 volumio-2 volumio[29667]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 29 23:35:15 volumio-2 volumio[29667]: info: camilladsp service started and running in background, instance 1 Nov 29 23:35:15 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:15 volumio-2 volumio[29667]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:15 volumio-2 volumio[29667]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 29 23:35:15 volumio-2 volumio[29667]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:15 volumio-2 volumio[29667]: info: FusionDsp loaded Nov 29 23:35:15 volumio-2 volumio[29667]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:15 volumio-2 sudo[29889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 29 23:35:15 volumio-2 sudo[29889]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:15 volumio-2 volumio[29667]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 29 23:35:15 volumio-2 volumio[29667]: info: Adding Signal Path Element [object Object] Nov 29 23:35:15 volumio-2 volumio[29667]: info: Adding fusiondspeq DSP Signal Path Element Nov 29 23:35:15 volumio-2 volumio[29667]: info: FusionDsp - ---- installed callbackRead Nov 29 23:35:15 volumio-2 sudo[29889]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:15 volumio-2 volumio[29667]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:15 volumio-2 volumio[29667]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 29 23:35:15 volumio-2 volumio[29667]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 29 23:35:15 volumio-2 volumio[29667]: at onErrorNT (internal/child_process.js:465:16) Nov 29 23:35:15 volumio-2 volumio[29667]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 29 23:35:15 volumio-2 volumio[29667]: errno: -13, Nov 29 23:35:15 volumio-2 volumio[29667]: code: 'EACCES', Nov 29 23:35:15 volumio-2 volumio[29667]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:15 volumio-2 volumio[29667]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:15 volumio-2 volumio[29667]: spawnargs: [ Nov 29 23:35:15 volumio-2 volumio[29667]: '-p', Nov 29 23:35:15 volumio-2 volumio[29667]: 9876, Nov 29 23:35:15 volumio-2 volumio[29667]: '-o', Nov 29 23:35:15 volumio-2 volumio[29667]: '/tmp/camilladsp.log', Nov 29 23:35:15 volumio-2 volumio[29667]: '-l', Nov 29 23:35:15 volumio-2 volumio[29667]: 'warn', Nov 29 23:35:15 volumio-2 volumio[29667]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 29 23:35:15 volumio-2 volumio[29667]: ] Nov 29 23:35:15 volumio-2 volumio[29667]: } Nov 29 23:35:15 volumio-2 volumio[29667]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:15 volumio-2 mpd[29877]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 23:35:15 volumio-2 mpd[29877]: output: No 'audio_output' defined in config file Nov 29 23:35:16 volumio-2 mpd[29877]: output: Successfully detected a sndio audio device Nov 29 23:35:16 volumio-2 mpd[29877]: zeroconf: No global port, disabling zeroconf Nov 29 23:35:16 volumio-2 systemd[1]: Started Music Player Daemon. Nov 29 23:35:16 volumio-2 sudo[29797]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:16 volumio-2 sudo[29762]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:16 volumio-2 sudo[29855]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:16 volumio-2 sudo[29903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 23:34 Nov 29 23:35:16 volumio-2 sudo[29903]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:16 volumio-2 sudo[29903]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:16 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 23:35:16 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 23:35:16 volumio-2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 29 23:35:16 volumio-2 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 29 23:35:16 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:16 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:16 volumio-2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 29 23:35:16 volumio-2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 114. Nov 29 23:35:17 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:17 volumio-2 systemd[1]: Stopped Volumio Backend Module. Nov 29 23:35:17 volumio-2 systemd[1]: Started Volumio Backend Module. Nov 29 23:35:17 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:18 volumio-2 volumio[29914]: info: ------------------------------------------- Nov 29 23:35:18 volumio-2 volumio[29914]: info: ----- Volumio3 ---- Nov 29 23:35:18 volumio-2 volumio[29914]: info: ------------------------------------------- Nov 29 23:35:18 volumio-2 volumio[29914]: info: ----- System startup ---- Nov 29 23:35:18 volumio-2 volumio[29914]: info: ------------------------------------------- Nov 29 23:35:19 volumio-2 volumio[29914]: info: MYVOLUMIO Environment detected Nov 29 23:35:19 volumio-2 volumio[29914]: info: Plugin folders cleanup Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning into folder /volumio/app/plugins/ Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category audio_interface Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category miscellanea Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category music_service Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category plugins.json Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category system_controller Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category user_interface Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning into folder /data/plugins/ Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category audio_interface Nov 29 23:35:19 volumio-2 volumio[29914]: info: Scanning category music_service Nov 29 23:35:19 volumio-2 volumio[29914]: info: Plugin folders cleanup completed Nov 29 23:35:19 volumio-2 volumio[29914]: info: ------------------------------------------- Nov 29 23:35:19 volumio-2 volumio[29914]: info: ----- Core plugins startup ---- Nov 29 23:35:19 volumio-2 volumio[29914]: info: ------------------------------------------- Nov 29 23:35:19 volumio-2 volumio[29914]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 23:35:19 volumio-2 volumio[29914]: info: Adding plugin upnp to MyMusic Plugins Nov 29 23:35:19 volumio-2 volumio[29914]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 23:35:19 volumio-2 volumio[29914]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 23:35:19 volumio-2 volumio[29914]: info: Loading plugins from folder /data/plugins/ Nov 29 23:35:19 volumio-2 volumio[29914]: info: Loading plugin "system"... Nov 29 23:35:19 volumio-2 volumio[29914]: info: Loading plugin "appearance"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "network"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: Refreshing Cached IP Addresses Nov 29 23:35:20 volumio-2 sudo[29940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 23:35:20 volumio-2 sudo[29940]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:20 volumio-2 sudo[29940]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:20 volumio-2 sudo[29942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "services"... Nov 29 23:35:20 volumio-2 sudo[29942]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "alsa_controller"... Nov 29 23:35:20 volumio-2 sudo[29942]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:20 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "wizard"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "networkfs"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: Starting Udev Watcher for removable devices Nov 29 23:35:20 volumio-2 volumio[29914]: info: Ignoring mount for partition: boot Nov 29 23:35:20 volumio-2 volumio[29914]: info: Ignoring mount for partition: volumio Nov 29 23:35:20 volumio-2 volumio[29914]: info: Ignoring mount for partition: volumio_data Nov 29 23:35:20 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "volumio_command_line_client"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "upnp"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: [1732923320548] Starting Upmpd Daemon Nov 29 23:35:20 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "my_music"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "mpd"... Nov 29 23:35:20 volumio-2 volumio[29914]: info: Loading plugin "upnp_browser"... Nov 29 23:35:21 volumio-2 volumio[29914]: info: Loading plugin "alarm-clock"... Nov 29 23:35:21 volumio-2 volumio[29914]: info: Loading plugin "airplay_emulation"... Nov 29 23:35:21 volumio-2 volumio[29914]: info: Starting Shairport Sync Nov 29 23:35:21 volumio-2 volumio[29914]: info: Loading plugin "last_100"... Nov 29 23:35:21 volumio-2 volumio[29914]: info: Loading plugin "webradio"... Nov 29 23:35:21 volumio-2 volumio[29914]: info: Loading plugin "i2s_dacs"... Nov 29 23:35:21 volumio-2 volumio[29914]: info: Loading plugin "volumiodiscovery"... Nov 29 23:35:21 volumio-2 volumio[29914]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:21 volumio-2 volumio[29914]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:21 volumio-2 volumio[29914]: *** WARNING *** For more information see Nov 29 23:35:21 volumio-2 volumio[29914]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:21 volumio-2 node[29914]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:21 volumio-2 volumio[29914]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:21 volumio-2 volumio[29914]: *** WARNING *** For more information see Nov 29 23:35:21 volumio-2 node[29914]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:21 volumio-2 node[29914]: *** WARNING *** For more information see Nov 29 23:35:21 volumio-2 node[29914]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:21 volumio-2 node[29914]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:21 volumio-2 node[29914]: *** WARNING *** For more information see Nov 29 23:35:21 volumio-2 volumio[29914]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 23:35:21 volumio-2 volumio[29914]: info: Discovery: Started advertising with name: Volumio 2 Nov 29 23:35:21 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:21 volumio-2 volumio[29914]: info: Loading plugin "spop"... Nov 29 23:35:21 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:21] [connect] Successful connection Nov 29 23:35:22 volumio-2 volumio[29914]: info: Loading plugin "outputs"... Nov 29 23:35:22 volumio-2 volumio[29914]: info: Loading plugin "albumart"... Nov 29 23:35:22 volumio-2 volumio[29914]: info: Plugin example_plugin is not enabled Nov 29 23:35:22 volumio-2 volumio[29914]: info: Loading plugin "inputs"... Nov 29 23:35:22 volumio-2 volumio[29914]: info: Loading plugin "updater_comm"... Nov 29 23:35:22 volumio-2 volumio[29914]: info: Plugin mpdemulation is not enabled Nov 29 23:35:22 volumio-2 volumio[29914]: info: Loading plugin "rest_api"... Nov 29 23:35:22 volumio-2 volumio[29914]: info: Loading plugin "websocket"... Nov 29 23:35:22 volumio-2 volumio[29914]: info: Starting Socket.io Server version 2.3.0 Nov 29 23:35:22 volumio-2 volumio[29914]: info: Loading plugin "fusiondsp"... Nov 29 23:35:22 volumio-2 volumio[29914]: Forking 3 albumart workers Nov 29 23:35:23 volumio-2 volumio[29914]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 23:35:23 volumio-2 volumio[29914]: info: Loading plugin "radio_paradise"... Nov 29 23:35:23 volumio-2 volumio[29914]: info: Applying required configuration parameters for plugin radio_paradise Nov 29 23:35:23 volumio-2 volumio[29914]: info: [1732923323410] [RadioParadise] API delay: 5 Nov 29 23:35:23 volumio-2 volumio[29914]: info: Loading i18n strings for locale en Nov 29 23:35:23 volumio-2 volumio[29914]: Updating browse sources language Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::initPlayerControls Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:23 volumio-2 volumio[29914]: Express server listening on port 3000 Nov 29 23:35:23 volumio-2 volumio[29914]: [Metrics] WebUI: 5s 522.83ms Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreStateMachine::resetVolumioState Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreStateMachine::getcurrentVolume Nov 29 23:35:23 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:23 volumio-2 volumio[29914]: Starting albumart workers Nov 29 23:35:23 volumio-2 volumio[29914]: info: Volumio Network Manager: Network status updated: 1 Nov 29 23:35:23 volumio-2 volumio[29914]: Starting albumart workers Nov 29 23:35:23 volumio-2 volumio[29914]: Starting albumart workers Nov 29 23:35:23 volumio-2 volumio[29914]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 29 23:35:24 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732923321 101 Nov 29 23:35:24 volumio-2 volumio[29914]: 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: 2 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:24 volumio-2 volumio[29914]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 29 23:35:24 volumio-2 volumio[29914]: info: Reloading queue from file Nov 29 23:35:24 volumio-2 volumio[29914]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreStateMachine::pushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreStateMachine::updateTrackBlock Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrackBlock Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreStateMachine::setRepeat null single undefined Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreStateMachine::pushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreStateMachine::setRandom null Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreStateMachine::pushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: Setting Device type: Raspberry PI Nov 29 23:35:24 volumio-2 volumio[29914]: info: Completed loading Core Plugins Nov 29 23:35:24 volumio-2 volumio[29914]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::Close All Modals sent Nov 29 23:35:24 volumio-2 volumio[29914]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:24 volumio-2 volumio[29914]: info: Reading ALSA contributions from plugins. Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 23:35:24 volumio-2 volumio[29914]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 29 23:35:24 volumio-2 volumio[29914]: info: Received Get System Version Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:24 volumio-2 volumio[29914]: info: Received Get System Info Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 23:35:24 volumio-2 volumio[29914]: info: Discovery: Getting this device information Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 23:35:24 volumio-2 volumio[29914]: info: Received Get System Info Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 23:35:24 volumio-2 volumio[29914]: info: Discovery: Getting this device information Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: Listing playlists Nov 29 23:35:24 volumio-2 volumio[29914]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreStateMachine::pushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:24 volumio-2 volumio[29914]: info: Discovery: adding 73caacf9-cf0b-4e00-807c-e3f5ec7e4332 Nov 29 23:35:24 volumio-2 volumio[29914]: info: Discovery: Found device Volumio 2 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:24 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:24 volumio-2 volumio[29914]: info: Output device has changed, restarting MPD Nov 29 23:35:24 volumio-2 volumio[29914]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:24 volumio-2 sudo[30023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:24 volumio-2 sudo[30023]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:24 volumio-2 sudo[30023]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:24 volumio-2 sudo[30025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:24 volumio-2 sudo[30025]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:24 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 29 23:35:24 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:24 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:24 volumio-2 volumio[29914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: ___________ START PLUGINS ___________ Nov 29 23:35:24 volumio-2 volumio[29914]: info: ControllerMpd::onStart: Initializing MPD Nov 29 23:35:24 volumio-2 volumio[29914]: info: Creating MPD Configuration file Nov 29 23:35:24 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:24 volumio-2 sudo[30041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:24 volumio-2 sudo[30041]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:24 volumio-2 volumio[29914]: info: [1732923324792] CoreMusicLibrary::Adding element Media Servers Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:24 volumio-2 sudo[30041]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:24 volumio-2 sudo[30044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:24 volumio-2 sudo[30044]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:24 volumio-2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 29 23:35:24 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:24 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:24 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:24 volumio-2 volumio[29914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:24 volumio-2 volumio[29914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:24 volumio-2 volumio[29914]: info: [1732923324930] CoreMusicLibrary::Adding element Last_100 Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:24 volumio-2 volumio[29914]: info: [1732923324934] CoreMusicLibrary::Adding element Webradio Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:24 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:24 volumio-2 volumio[29914]: info: Initializing BBC Radios Nov 29 23:35:24 volumio-2 sudo[30046]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:24 volumio-2 sudo[30046]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:24 volumio-2 sudo[30046]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: Creating Spotify config file Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: Loading i18n strings for locale en Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:25 volumio-2 volumio[29914]: info: [1732923325060] CoreMusicLibrary::Adding element Radio Paradise Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:25 volumio-2 volumio[29914]: Cannot find translation for source Radio Paradise Nov 29 23:35:25 volumio-2 volumio[29914]: info: Volumio Calling Home Nov 29 23:35:25 volumio-2 volumio[29914]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:25 volumio-2 volumio[29914]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:25 volumio-2 volumio[29914]: info: Reading ALSA contributions from plugins. Nov 29 23:35:25 volumio-2 volumio[29914]: info: MPD Permissions set Nov 29 23:35:25 volumio-2 volumio[29914]: info: MPD Permissions set Nov 29 23:35:25 volumio-2 volumio[29914]: info: Volumio called home Nov 29 23:35:25 volumio-2 volumio[29914]: info: Spotify config file written Nov 29 23:35:25 volumio-2 volumio[29914]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 sudo[30069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 23:35:25 volumio-2 sudo[30069]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 systemd[1]: Stopping go-librespot Daemon... Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:25 volumio-2 systemd[1]: go-librespot-daemon.service: Succeeded. Nov 29 23:35:25 volumio-2 systemd[1]: Stopped go-librespot Daemon. Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:25 volumio-2 volumio[29914]: info: No need to fix Spotify hosts Nov 29 23:35:25 volumio-2 systemd[1]: Started go-librespot Daemon. Nov 29 23:35:25 volumio-2 go-librespot[30076]: Librespot-go daemon starting... Nov 29 23:35:25 volumio-2 sudo[30069]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:25 volumio-2 go-librespot[30076]: time="2024-11-29T23:35:25Z" level=info msg="generated new device id: 5fe43b27050104f33736126bf9c7d1828ef41d76" Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:25 volumio-2 volumio[29914]: info: Starting Shairport Sync Nov 29 23:35:25 volumio-2 volumio[29914]: info: Starting Shairport Sync Nov 29 23:35:25 volumio-2 volumio[29914]: info: Starting Shairport Sync Nov 29 23:35:25 volumio-2 sudo[30087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:25 volumio-2 sudo[30090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:25 volumio-2 sudo[30090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:25 volumio-2 sudo[30087]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:25 volumio-2 volumio[29914]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:25 volumio-2 volumio[29914]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:25 volumio-2 volumio[29914]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:25 volumio-2 volumio[29914]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:25 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:25 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:25 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:25 volumio-2 sudo[30092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:25 volumio-2 sudo[30092]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:25 volumio-2 go-librespot[30076]: time="2024-11-29T23:35:25Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 29 23:35:25 volumio-2 go-librespot[30076]: time="2024-11-29T23:35:25Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 29 23:35:25 volumio-2 go-librespot[30076]: time="2024-11-29T23:35:25Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 29 23:35:25 volumio-2 go-librespot[30076]: time="2024-11-29T23:35:25Z" level=debug msg="zeroconf server listening on port 39269" Nov 29 23:35:25 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:25 volumio-2 sudo[30090]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:25 volumio-2 sudo[30087]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:25 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:25 volumio-2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:25 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:25 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:25 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:25 volumio-2 sudo[30092]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:26 volumio-2 volumio[29914]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 29 23:35:26 volumio-2 volumio[29914]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:26 volumio-2 volumio[29914]: info: Output device has changed, restarting MPD Nov 29 23:35:26 volumio-2 sudo[30103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:26 volumio-2 volumio[29914]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:26 volumio-2 sudo[30103]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:26 volumio-2 sudo[30103]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:26 volumio-2 sudo[30105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:26 volumio-2 sudo[30105]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:26 volumio-2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:26 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:26 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:26 volumio-2 volumio[29914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:26 volumio-2 volumio[29914]: info: Shairport-Sync Started Nov 29 23:35:26 volumio-2 volumio[29914]: Error adding Membership: Error: addMembership EINVAL Nov 29 23:35:26 volumio-2 volumio[29914]: info: Shairport-Sync Started Nov 29 23:35:26 volumio-2 volumio[29914]: info: Shairport-Sync Started Nov 29 23:35:26 volumio-2 volumio[29914]: info: MPD Permissions set Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:26 volumio-2 volumio[29914]: info: Starting Shairport Sync Nov 29 23:35:26 volumio-2 volumio[29914]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:26 volumio-2 sudo[30118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:26 volumio-2 sudo[30118]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:26 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:26 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:26 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:26 volumio-2 sudo[30111]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:26 volumio-2 sudo[30111]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:26 volumio-2 sudo[30111]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:26 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:26 volumio-2 sudo[30118]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:26 volumio-2 volumio[29914]: info: Shairport-Sync Started Nov 29 23:35:27 volumio-2 volumio[29914]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:27 volumio-2 volumio[29914]: 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 Nov 29 23:35:27 volumio-2 volumio[29914]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:27 volumio-2 volumio[29914]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 29 23:35:27 volumio-2 volumio[29914]: info: camilladsp service started and running in background, instance 1 Nov 29 23:35:27 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:27 volumio-2 volumio[29914]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:27 volumio-2 volumio[29914]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 29 23:35:27 volumio-2 volumio[29914]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:27 volumio-2 volumio[29914]: info: FusionDsp loaded Nov 29 23:35:27 volumio-2 volumio[29914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:27 volumio-2 sudo[30134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 29 23:35:27 volumio-2 sudo[30134]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:27 volumio-2 sudo[30134]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:27 volumio-2 volumio[29914]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 29 23:35:27 volumio-2 volumio[29914]: info: Adding Signal Path Element [object Object] Nov 29 23:35:27 volumio-2 volumio[29914]: info: Adding fusiondspeq DSP Signal Path Element Nov 29 23:35:27 volumio-2 volumio[29914]: info: FusionDsp - ---- installed callbackRead Nov 29 23:35:27 volumio-2 volumio[29914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:27 volumio-2 volumio[29914]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 29 23:35:27 volumio-2 volumio[29914]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 29 23:35:27 volumio-2 volumio[29914]: at onErrorNT (internal/child_process.js:465:16) Nov 29 23:35:27 volumio-2 volumio[29914]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 29 23:35:27 volumio-2 volumio[29914]: errno: -13, Nov 29 23:35:27 volumio-2 volumio[29914]: code: 'EACCES', Nov 29 23:35:27 volumio-2 volumio[29914]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:27 volumio-2 volumio[29914]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:27 volumio-2 volumio[29914]: spawnargs: [ Nov 29 23:35:27 volumio-2 volumio[29914]: '-p', Nov 29 23:35:27 volumio-2 volumio[29914]: 9876, Nov 29 23:35:27 volumio-2 volumio[29914]: '-o', Nov 29 23:35:27 volumio-2 volumio[29914]: '/tmp/camilladsp.log', Nov 29 23:35:27 volumio-2 volumio[29914]: '-l', Nov 29 23:35:27 volumio-2 volumio[29914]: 'warn', Nov 29 23:35:27 volumio-2 volumio[29914]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 29 23:35:27 volumio-2 volumio[29914]: ] Nov 29 23:35:27 volumio-2 volumio[29914]: } Nov 29 23:35:27 volumio-2 volumio[29914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:27 volumio-2 sudo[30146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 23:34 Nov 29 23:35:27 volumio-2 sudo[30146]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:27 volumio-2 mpd[30122]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 23:35:27 volumio-2 mpd[30122]: output: No 'audio_output' defined in config file Nov 29 23:35:27 volumio-2 mpd[30122]: output: Successfully detected a sndio audio device Nov 29 23:35:27 volumio-2 mpd[30122]: zeroconf: No global port, disabling zeroconf Nov 29 23:35:27 volumio-2 systemd[1]: Started Music Player Daemon. Nov 29 23:35:27 volumio-2 sudo[30105]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:27 volumio-2 sudo[30025]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:27 volumio-2 sudo[30044]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:27 volumio-2 sudo[30146]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:28 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 23:35:28 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 23:35:28 volumio-2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 29 23:35:28 volumio-2 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 29 23:35:28 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:28 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:28 volumio-2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 29 23:35:28 volumio-2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 115. Nov 29 23:35:28 volumio-2 systemd[1]: Stopped Volumio Backend Module. Nov 29 23:35:28 volumio-2 systemd[1]: Started Volumio Backend Module. Nov 29 23:35:28 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:28 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:29 volumio-2 volumio[30159]: info: ------------------------------------------- Nov 29 23:35:29 volumio-2 volumio[30159]: info: ----- Volumio3 ---- Nov 29 23:35:29 volumio-2 volumio[30159]: info: ------------------------------------------- Nov 29 23:35:29 volumio-2 volumio[30159]: info: ----- System startup ---- Nov 29 23:35:29 volumio-2 volumio[30159]: info: ------------------------------------------- Nov 29 23:35:30 volumio-2 volumio[30159]: info: MYVOLUMIO Environment detected Nov 29 23:35:30 volumio-2 volumio[30159]: info: Plugin folders cleanup Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning into folder /volumio/app/plugins/ Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category audio_interface Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category miscellanea Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category music_service Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category plugins.json Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category system_controller Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category user_interface Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning into folder /data/plugins/ Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category audio_interface Nov 29 23:35:30 volumio-2 volumio[30159]: info: Scanning category music_service Nov 29 23:35:30 volumio-2 volumio[30159]: info: Plugin folders cleanup completed Nov 29 23:35:30 volumio-2 volumio[30159]: info: ------------------------------------------- Nov 29 23:35:30 volumio-2 volumio[30159]: info: ----- Core plugins startup ---- Nov 29 23:35:30 volumio-2 volumio[30159]: info: ------------------------------------------- Nov 29 23:35:30 volumio-2 volumio[30159]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 23:35:30 volumio-2 volumio[30159]: info: Adding plugin upnp to MyMusic Plugins Nov 29 23:35:30 volumio-2 volumio[30159]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 23:35:30 volumio-2 volumio[30159]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 23:35:30 volumio-2 volumio[30159]: info: Loading plugins from folder /data/plugins/ Nov 29 23:35:30 volumio-2 volumio[30159]: info: Loading plugin "system"... Nov 29 23:35:30 volumio-2 volumio[30159]: info: Loading plugin "appearance"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "network"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: Refreshing Cached IP Addresses Nov 29 23:35:31 volumio-2 sudo[30186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 23:35:31 volumio-2 sudo[30186]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:31 volumio-2 sudo[30186]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:31 volumio-2 sudo[30188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 23:35:31 volumio-2 sudo[30188]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "services"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "alsa_controller"... Nov 29 23:35:31 volumio-2 sudo[30188]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:31 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "wizard"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "networkfs"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: Starting Udev Watcher for removable devices Nov 29 23:35:31 volumio-2 volumio[30159]: info: Ignoring mount for partition: boot Nov 29 23:35:31 volumio-2 volumio[30159]: info: Ignoring mount for partition: volumio Nov 29 23:35:31 volumio-2 volumio[30159]: info: Ignoring mount for partition: volumio_data Nov 29 23:35:31 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "volumio_command_line_client"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "upnp"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: [1732923331759] Starting Upmpd Daemon Nov 29 23:35:31 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "my_music"... Nov 29 23:35:31 volumio-2 volumio[30159]: info: Loading plugin "mpd"... Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "upnp_browser"... Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "alarm-clock"... Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "airplay_emulation"... Nov 29 23:35:32 volumio-2 volumio[30159]: info: Starting Shairport Sync Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "last_100"... Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "webradio"... Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "i2s_dacs"... Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "volumiodiscovery"... Nov 29 23:35:32 volumio-2 volumio[30159]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:32 volumio-2 volumio[30159]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:32 volumio-2 volumio[30159]: *** WARNING *** For more information see Nov 29 23:35:32 volumio-2 volumio[30159]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:32 volumio-2 node[30159]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:32 volumio-2 volumio[30159]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:32 volumio-2 volumio[30159]: *** WARNING *** For more information see Nov 29 23:35:32 volumio-2 node[30159]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:32 volumio-2 node[30159]: *** WARNING *** For more information see Nov 29 23:35:32 volumio-2 node[30159]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:32 volumio-2 node[30159]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:32 volumio-2 node[30159]: *** WARNING *** For more information see Nov 29 23:35:32 volumio-2 volumio[30159]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 23:35:32 volumio-2 volumio[30159]: info: Discovery: Started advertising with name: Volumio 2 Nov 29 23:35:32 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:32 volumio-2 volumio[30159]: info: Loading plugin "spop"... Nov 29 23:35:33 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:33] [connect] Successful connection Nov 29 23:35:33 volumio-2 volumio[30159]: info: Loading plugin "outputs"... Nov 29 23:35:33 volumio-2 volumio[30159]: info: Loading plugin "albumart"... Nov 29 23:35:33 volumio-2 volumio[30159]: info: Plugin example_plugin is not enabled Nov 29 23:35:33 volumio-2 volumio[30159]: info: Loading plugin "inputs"... Nov 29 23:35:33 volumio-2 volumio[30159]: info: Loading plugin "updater_comm"... Nov 29 23:35:33 volumio-2 volumio[30159]: info: Plugin mpdemulation is not enabled Nov 29 23:35:33 volumio-2 volumio[30159]: info: Loading plugin "rest_api"... Nov 29 23:35:33 volumio-2 volumio[30159]: info: Loading plugin "websocket"... Nov 29 23:35:33 volumio-2 volumio[30159]: info: Starting Socket.io Server version 2.3.0 Nov 29 23:35:33 volumio-2 volumio[30159]: info: Loading plugin "fusiondsp"... Nov 29 23:35:34 volumio-2 volumio[30159]: Forking 3 albumart workers Nov 29 23:35:34 volumio-2 volumio[30159]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 23:35:34 volumio-2 volumio[30159]: info: Loading plugin "radio_paradise"... Nov 29 23:35:34 volumio-2 volumio[30159]: info: Applying required configuration parameters for plugin radio_paradise Nov 29 23:35:34 volumio-2 volumio[30159]: info: [1732923334584] [RadioParadise] API delay: 5 Nov 29 23:35:34 volumio-2 volumio[30159]: info: Loading i18n strings for locale en Nov 29 23:35:34 volumio-2 volumio[30159]: Updating browse sources language Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::initPlayerControls Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:34 volumio-2 volumio[30159]: Express server listening on port 3000 Nov 29 23:35:34 volumio-2 volumio[30159]: [Metrics] WebUI: 5s 537.07ms Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreStateMachine::resetVolumioState Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreStateMachine::getcurrentVolume Nov 29 23:35:34 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:35 volumio-2 volumio[30159]: info: Volumio Network Manager: Network status updated: 1 Nov 29 23:35:35 volumio-2 volumio[30159]: Starting albumart workers Nov 29 23:35:35 volumio-2 volumio[30159]: Starting albumart workers Nov 29 23:35:35 volumio-2 volumio[30159]: Starting albumart workers Nov 29 23:35:35 volumio-2 volumio[30159]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 29 23:35:35 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732923333 101 Nov 29 23:35:35 volumio-2 volumio[30159]: 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: 2 Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:35 volumio-2 volumio[30159]: info: Reloading queue from file Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreStateMachine::setRepeat null single undefined Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreStateMachine::pushState Nov 29 23:35:35 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreStateMachine::setRandom null Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreStateMachine::pushState Nov 29 23:35:35 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:35 volumio-2 volumio[30159]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreStateMachine::pushState Nov 29 23:35:35 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreStateMachine::updateTrackBlock Nov 29 23:35:35 volumio-2 volumio[30159]: info: CorePlayQueue::getTrackBlock Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:35 volumio-2 volumio[30159]: info: Setting Device type: Raspberry PI Nov 29 23:35:35 volumio-2 volumio[30159]: info: Completed loading Core Plugins Nov 29 23:35:35 volumio-2 volumio[30159]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:35 volumio-2 volumio[30159]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:35 volumio-2 volumio[30159]: info: Reading ALSA contributions from plugins. Nov 29 23:35:35 volumio-2 volumio[30159]: info: Discovery: adding 73caacf9-cf0b-4e00-807c-e3f5ec7e4332 Nov 29 23:35:35 volumio-2 volumio[30159]: info: Discovery: Found device Volumio 2 Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:35 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:35 volumio-2 volumio[30159]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:35 volumio-2 volumio[30159]: info: Output device has changed, restarting MPD Nov 29 23:35:35 volumio-2 volumio[30159]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:35 volumio-2 sudo[30254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:35 volumio-2 sudo[30254]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:35 volumio-2 sudo[30254]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:35 volumio-2 sudo[30256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:35 volumio-2 sudo[30256]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:35 volumio-2 volumio[30159]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:35 volumio-2 volumio[30159]: info: ___________ START PLUGINS ___________ Nov 29 23:35:35 volumio-2 volumio[30159]: info: ControllerMpd::onStart: Initializing MPD Nov 29 23:35:35 volumio-2 volumio[30159]: info: Creating MPD Configuration file Nov 29 23:35:35 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 29 23:35:35 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:35 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:35 volumio-2 volumio[30159]: info: [1732923335788] CoreMusicLibrary::Adding element Media Servers Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:35 volumio-2 sudo[30265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:35 volumio-2 sudo[30265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:35 volumio-2 sudo[30264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:35 volumio-2 sudo[30264]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:35 volumio-2 sudo[30264]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:35 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:35 volumio-2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 29 23:35:35 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:35 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:35 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:35 volumio-2 volumio[30159]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:35 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:36 volumio-2 sudo[30288]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:36 volumio-2 sudo[30288]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:36 volumio-2 sudo[30288]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:36 volumio-2 volumio[30159]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:36 volumio-2 volumio[30159]: info: [1732923336050] CoreMusicLibrary::Adding element Last_100 Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:36 volumio-2 volumio[30159]: info: [1732923336054] CoreMusicLibrary::Adding element Webradio Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:36 volumio-2 volumio[30159]: info: Initializing BBC Radios Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: Creating Spotify config file Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: Loading i18n strings for locale en Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:36 volumio-2 volumio[30159]: info: [1732923336198] CoreMusicLibrary::Adding element Radio Paradise Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:36 volumio-2 volumio[30159]: Cannot find translation for source Radio Paradise Nov 29 23:35:36 volumio-2 volumio[30159]: info: Volumio Calling Home Nov 29 23:35:36 volumio-2 volumio[30159]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:36 volumio-2 volumio[30159]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:36 volumio-2 volumio[30159]: info: Reading ALSA contributions from plugins. Nov 29 23:35:36 volumio-2 volumio[30159]: info: MPD Permissions set Nov 29 23:35:36 volumio-2 volumio[30159]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreStateMachine::pushState Nov 29 23:35:36 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:36 volumio-2 volumio[30159]: info: Volumio called home Nov 29 23:35:36 volumio-2 volumio[30159]: info: Spotify config file written Nov 29 23:35:36 volumio-2 volumio[30159]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 sudo[30316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 23:35:36 volumio-2 sudo[30316]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 systemd[1]: Stopping go-librespot Daemon... Nov 29 23:35:36 volumio-2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:36 volumio-2 systemd[1]: go-librespot-daemon.service: Succeeded. Nov 29 23:35:36 volumio-2 systemd[1]: Stopped go-librespot Daemon. Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:36 volumio-2 volumio[30159]: info: No need to fix Spotify hosts Nov 29 23:35:36 volumio-2 systemd[1]: Started go-librespot Daemon. Nov 29 23:35:36 volumio-2 go-librespot[30322]: Librespot-go daemon starting... Nov 29 23:35:36 volumio-2 sudo[30316]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:36 volumio-2 go-librespot[30322]: time="2024-11-29T23:35:36Z" level=info msg="generated new device id: 425fbdba93a4b0a4792dfa70df02aa0229a953ef" Nov 29 23:35:36 volumio-2 volumio[30159]: info: Starting Shairport Sync Nov 29 23:35:36 volumio-2 volumio[30159]: info: Starting Shairport Sync Nov 29 23:35:36 volumio-2 sudo[30334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:36 volumio-2 volumio[30159]: info: Starting Shairport Sync Nov 29 23:35:36 volumio-2 sudo[30334]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:36 volumio-2 volumio[30159]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:36 volumio-2 volumio[30159]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:36 volumio-2 sudo[30336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:36 volumio-2 sudo[30336]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:36 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:36 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:36 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:36 volumio-2 sudo[30339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:36 volumio-2 sudo[30339]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:36 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:36 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:36 volumio-2 volumio[30159]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 29 23:35:36 volumio-2 go-librespot[30322]: time="2024-11-29T23:35:36Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 29 23:35:36 volumio-2 go-librespot[30322]: time="2024-11-29T23:35:36Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 29 23:35:36 volumio-2 go-librespot[30322]: time="2024-11-29T23:35:36Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 29 23:35:36 volumio-2 go-librespot[30322]: time="2024-11-29T23:35:36Z" level=debug msg="zeroconf server listening on port 38403" Nov 29 23:35:36 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:36 volumio-2 volumio[30159]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:36 volumio-2 sudo[30334]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:36 volumio-2 volumio[30159]: info: Output device has changed, restarting MPD Nov 29 23:35:36 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:36 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:36 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:36 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:36 volumio-2 sudo[30339]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:36 volumio-2 sudo[30336]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:36 volumio-2 sudo[30347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:36 volumio-2 sudo[30347]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:37 volumio-2 volumio[30159]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:37 volumio-2 sudo[30347]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:37 volumio-2 sudo[30351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:37 volumio-2 sudo[30351]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:37 volumio-2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:37 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:37 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:37 volumio-2 volumio[30159]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:37 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:37 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 23:35:37 volumio-2 volumio[30159]: info: Received Get System Info Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 23:35:37 volumio-2 volumio[30159]: info: Discovery: Getting this device information Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:37 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:37 volumio-2 volumio[30159]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:37 volumio-2 volumio[30159]: info: Listing playlists Nov 29 23:35:37 volumio-2 volumio[30159]: info: MPD Permissions set Nov 29 23:35:37 volumio-2 volumio[30159]: info: Shairport-Sync Started Nov 29 23:35:37 volumio-2 volumio[30159]: Error adding Membership: Error: addMembership EINVAL Nov 29 23:35:37 volumio-2 volumio[30159]: info: Shairport-Sync Started Nov 29 23:35:37 volumio-2 volumio[30159]: info: Shairport-Sync Started Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:37 volumio-2 volumio[30159]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 29 23:35:37 volumio-2 volumio[30159]: info: Starting Shairport Sync Nov 29 23:35:37 volumio-2 sudo[30358]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:37 volumio-2 sudo[30358]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:37 volumio-2 sudo[30358]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:37 volumio-2 volumio[30159]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:37 volumio-2 sudo[30366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:37 volumio-2 sudo[30366]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:37 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:37 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:37 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:37 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:37 volumio-2 sudo[30366]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:37 volumio-2 volumio[30159]: info: Shairport-Sync Started Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:37 volumio-2 volumio[30159]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:38 volumio-2 volumio[30159]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:38 volumio-2 volumio[30159]: 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 Nov 29 23:35:38 volumio-2 volumio[30159]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:38 volumio-2 volumio[30159]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 29 23:35:38 volumio-2 volumio[30159]: info: camilladsp service started and running in background, instance 1 Nov 29 23:35:38 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:38 volumio-2 volumio[30159]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:38 volumio-2 volumio[30159]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 29 23:35:38 volumio-2 volumio[30159]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:38 volumio-2 volumio[30159]: info: FusionDsp loaded Nov 29 23:35:38 volumio-2 volumio[30159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:38 volumio-2 sudo[30383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 29 23:35:38 volumio-2 sudo[30383]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:38 volumio-2 volumio[30159]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 29 23:35:38 volumio-2 volumio[30159]: info: Adding Signal Path Element [object Object] Nov 29 23:35:38 volumio-2 volumio[30159]: info: Adding fusiondspeq DSP Signal Path Element Nov 29 23:35:38 volumio-2 volumio[30159]: info: FusionDsp - ---- installed callbackRead Nov 29 23:35:38 volumio-2 sudo[30383]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:38 volumio-2 volumio[30159]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:38 volumio-2 volumio[30159]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 29 23:35:38 volumio-2 volumio[30159]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 29 23:35:38 volumio-2 volumio[30159]: at onErrorNT (internal/child_process.js:465:16) Nov 29 23:35:38 volumio-2 volumio[30159]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 29 23:35:38 volumio-2 volumio[30159]: errno: -13, Nov 29 23:35:38 volumio-2 volumio[30159]: code: 'EACCES', Nov 29 23:35:38 volumio-2 volumio[30159]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:38 volumio-2 volumio[30159]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:38 volumio-2 volumio[30159]: spawnargs: [ Nov 29 23:35:38 volumio-2 volumio[30159]: '-p', Nov 29 23:35:38 volumio-2 volumio[30159]: 9876, Nov 29 23:35:38 volumio-2 volumio[30159]: '-o', Nov 29 23:35:38 volumio-2 volumio[30159]: '/tmp/camilladsp.log', Nov 29 23:35:38 volumio-2 volumio[30159]: '-l', Nov 29 23:35:38 volumio-2 volumio[30159]: 'warn', Nov 29 23:35:38 volumio-2 volumio[30159]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 29 23:35:38 volumio-2 volumio[30159]: ] Nov 29 23:35:38 volumio-2 volumio[30159]: } Nov 29 23:35:38 volumio-2 volumio[30159]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:38 volumio-2 mpd[30367]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 23:35:38 volumio-2 mpd[30367]: output: No 'audio_output' defined in config file Nov 29 23:35:38 volumio-2 mpd[30367]: output: Successfully detected a sndio audio device Nov 29 23:35:38 volumio-2 mpd[30367]: zeroconf: No global port, disabling zeroconf Nov 29 23:35:38 volumio-2 systemd[1]: Started Music Player Daemon. Nov 29 23:35:38 volumio-2 sudo[30265]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:38 volumio-2 sudo[30256]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:38 volumio-2 sudo[30351]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:38 volumio-2 sudo[30397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 23:34 Nov 29 23:35:38 volumio-2 sudo[30397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:39 volumio-2 sudo[30397]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:39 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 23:35:39 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 23:35:39 volumio-2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 29 23:35:39 volumio-2 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 29 23:35:39 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:39 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:39 volumio-2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 29 23:35:39 volumio-2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 116. Nov 29 23:35:39 volumio-2 systemd[1]: Stopped Volumio Backend Module. Nov 29 23:35:39 volumio-2 systemd[1]: Started Volumio Backend Module. Nov 29 23:35:39 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:39 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:41 volumio-2 volumio[30407]: info: ------------------------------------------- Nov 29 23:35:41 volumio-2 volumio[30407]: info: ----- Volumio3 ---- Nov 29 23:35:41 volumio-2 volumio[30407]: info: ------------------------------------------- Nov 29 23:35:41 volumio-2 volumio[30407]: info: ----- System startup ---- Nov 29 23:35:41 volumio-2 volumio[30407]: info: ------------------------------------------- Nov 29 23:35:41 volumio-2 volumio[30407]: info: MYVOLUMIO Environment detected Nov 29 23:35:41 volumio-2 volumio[30407]: info: Plugin folders cleanup Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning into folder /volumio/app/plugins/ Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category audio_interface Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category miscellanea Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category music_service Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category plugins.json Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category system_controller Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category user_interface Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning into folder /data/plugins/ Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category audio_interface Nov 29 23:35:41 volumio-2 volumio[30407]: info: Scanning category music_service Nov 29 23:35:41 volumio-2 volumio[30407]: info: Plugin folders cleanup completed Nov 29 23:35:41 volumio-2 volumio[30407]: info: ------------------------------------------- Nov 29 23:35:41 volumio-2 volumio[30407]: info: ----- Core plugins startup ---- Nov 29 23:35:41 volumio-2 volumio[30407]: info: ------------------------------------------- Nov 29 23:35:41 volumio-2 volumio[30407]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 23:35:41 volumio-2 volumio[30407]: info: Adding plugin upnp to MyMusic Plugins Nov 29 23:35:41 volumio-2 volumio[30407]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 23:35:41 volumio-2 volumio[30407]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 23:35:41 volumio-2 volumio[30407]: info: Loading plugins from folder /data/plugins/ Nov 29 23:35:41 volumio-2 volumio[30407]: info: Loading plugin "system"... Nov 29 23:35:41 volumio-2 volumio[30407]: info: Loading plugin "appearance"... Nov 29 23:35:42 volumio-2 volumio[30407]: info: Loading plugin "network"... Nov 29 23:35:42 volumio-2 volumio[30407]: info: Refreshing Cached IP Addresses Nov 29 23:35:42 volumio-2 sudo[30434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 23:35:42 volumio-2 sudo[30434]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:42 volumio-2 sudo[30434]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:42 volumio-2 volumio[30407]: info: Loading plugin "services"... Nov 29 23:35:42 volumio-2 sudo[30436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 23:35:42 volumio-2 sudo[30436]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:42 volumio-2 volumio[30407]: info: Loading plugin "alsa_controller"... Nov 29 23:35:42 volumio-2 sudo[30436]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:42 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "wizard"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "networkfs"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Starting Udev Watcher for removable devices Nov 29 23:35:43 volumio-2 volumio[30407]: info: Ignoring mount for partition: boot Nov 29 23:35:43 volumio-2 volumio[30407]: info: Ignoring mount for partition: volumio Nov 29 23:35:43 volumio-2 volumio[30407]: info: Ignoring mount for partition: volumio_data Nov 29 23:35:43 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "volumio_command_line_client"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "upnp"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: [1732923343071] Starting Upmpd Daemon Nov 29 23:35:43 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "my_music"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "mpd"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "upnp_browser"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "alarm-clock"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "airplay_emulation"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Starting Shairport Sync Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "last_100"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "webradio"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "i2s_dacs"... Nov 29 23:35:43 volumio-2 volumio[30407]: info: Loading plugin "volumiodiscovery"... Nov 29 23:35:43 volumio-2 volumio[30407]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:43 volumio-2 volumio[30407]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:43 volumio-2 volumio[30407]: *** WARNING *** For more information see Nov 29 23:35:43 volumio-2 node[30407]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:43 volumio-2 volumio[30407]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:43 volumio-2 volumio[30407]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:43 volumio-2 volumio[30407]: *** WARNING *** For more information see Nov 29 23:35:43 volumio-2 node[30407]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:43 volumio-2 node[30407]: *** WARNING *** For more information see Nov 29 23:35:43 volumio-2 node[30407]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:43 volumio-2 node[30407]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:43 volumio-2 node[30407]: *** WARNING *** For more information see Nov 29 23:35:44 volumio-2 volumio[30407]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 23:35:44 volumio-2 volumio[30407]: info: Discovery: Started advertising with name: Volumio 2 Nov 29 23:35:44 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:44 volumio-2 volumio[30407]: info: Loading plugin "spop"... Nov 29 23:35:44 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:44] [connect] Successful connection Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "outputs"... Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "albumart"... Nov 29 23:35:45 volumio-2 volumio[30407]: info: Plugin example_plugin is not enabled Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "inputs"... Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "updater_comm"... Nov 29 23:35:45 volumio-2 volumio[30407]: info: Plugin mpdemulation is not enabled Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "rest_api"... Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "websocket"... Nov 29 23:35:45 volumio-2 volumio[30407]: info: Starting Socket.io Server version 2.3.0 Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "fusiondsp"... Nov 29 23:35:45 volumio-2 volumio[30407]: Forking 3 albumart workers Nov 29 23:35:45 volumio-2 volumio[30407]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 23:35:45 volumio-2 volumio[30407]: info: Loading plugin "radio_paradise"... Nov 29 23:35:45 volumio-2 volumio[30407]: info: Applying required configuration parameters for plugin radio_paradise Nov 29 23:35:45 volumio-2 volumio[30407]: info: [1732923345977] [RadioParadise] API delay: 5 Nov 29 23:35:46 volumio-2 volumio[30407]: info: Loading i18n strings for locale en Nov 29 23:35:46 volumio-2 volumio[30407]: Updating browse sources language Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::initPlayerControls Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: Express server listening on port 3000 Nov 29 23:35:46 volumio-2 volumio[30407]: [Metrics] WebUI: 5s 586.66ms Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::resetVolumioState Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::getcurrentVolume Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:46 volumio-2 volumio[30407]: info: Volumio Network Manager: Network status updated: 1 Nov 29 23:35:46 volumio-2 volumio[30407]: Starting albumart workers Nov 29 23:35:46 volumio-2 volumio[30407]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 29 23:35:46 volumio-2 volumio[30407]: Starting albumart workers Nov 29 23:35:46 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732923344 101 Nov 29 23:35:46 volumio-2 volumio[30407]: 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: 2 Nov 29 23:35:46 volumio-2 volumio[30407]: Starting albumart workers Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: Reloading queue from file Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::setRepeat null single undefined Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::pushState Nov 29 23:35:46 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::setRandom null Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::pushState Nov 29 23:35:46 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:46 volumio-2 volumio[30407]: info: Setting Device type: Raspberry PI Nov 29 23:35:46 volumio-2 volumio[30407]: info: Completed loading Core Plugins Nov 29 23:35:46 volumio-2 volumio[30407]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:46 volumio-2 volumio[30407]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::pushState Nov 29 23:35:46 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreStateMachine::updateTrackBlock Nov 29 23:35:46 volumio-2 volumio[30407]: info: CorePlayQueue::getTrackBlock Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:46 volumio-2 volumio[30407]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:46 volumio-2 volumio[30407]: info: Reading ALSA contributions from plugins. Nov 29 23:35:46 volumio-2 volumio[30407]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:46 volumio-2 volumio[30407]: info: Output device has changed, restarting MPD Nov 29 23:35:46 volumio-2 volumio[30407]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:46 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:46 volumio-2 sudo[30501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:46 volumio-2 sudo[30501]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:46 volumio-2 sudo[30501]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:46 volumio-2 sudo[30503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:46 volumio-2 sudo[30503]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:47 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 29 23:35:47 volumio-2 volumio[30407]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:47 volumio-2 volumio[30407]: info: ___________ START PLUGINS ___________ Nov 29 23:35:47 volumio-2 volumio[30407]: info: ControllerMpd::onStart: Initializing MPD Nov 29 23:35:47 volumio-2 volumio[30407]: info: Creating MPD Configuration file Nov 29 23:35:47 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:47 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:47 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:47 volumio-2 volumio[30407]: info: [1732923347093] CoreMusicLibrary::Adding element Media Servers Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:47 volumio-2 sudo[30510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:47 volumio-2 sudo[30510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:47 volumio-2 sudo[30510]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:47 volumio-2 sudo[30513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:47 volumio-2 sudo[30513]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:47 volumio-2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 29 23:35:47 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:47 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:47 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:47 volumio-2 volumio[30407]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:47 volumio-2 volumio[30407]: info: [1732923347296] CoreMusicLibrary::Adding element Last_100 Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:47 volumio-2 volumio[30407]: info: [1732923347301] CoreMusicLibrary::Adding element Webradio Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:47 volumio-2 volumio[30407]: info: Initializing BBC Radios Nov 29 23:35:47 volumio-2 sudo[30524]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:47 volumio-2 sudo[30524]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:47 volumio-2 sudo[30524]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: Creating Spotify config file Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: Loading i18n strings for locale en Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:47 volumio-2 volumio[30407]: info: [1732923347477] CoreMusicLibrary::Adding element Radio Paradise Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:47 volumio-2 volumio[30407]: Cannot find translation for source Radio Paradise Nov 29 23:35:47 volumio-2 volumio[30407]: info: Volumio Calling Home Nov 29 23:35:47 volumio-2 volumio[30407]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:47 volumio-2 volumio[30407]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:47 volumio-2 volumio[30407]: info: Reading ALSA contributions from plugins. Nov 29 23:35:47 volumio-2 volumio[30407]: info: MPD Permissions set Nov 29 23:35:47 volumio-2 volumio[30407]: info: MPD Permissions set Nov 29 23:35:47 volumio-2 volumio[30407]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreStateMachine::pushState Nov 29 23:35:47 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:47 volumio-2 volumio[30407]: info: Discovery: adding 73caacf9-cf0b-4e00-807c-e3f5ec7e4332 Nov 29 23:35:47 volumio-2 volumio[30407]: info: Discovery: Found device Volumio 2 Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:47 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:47 volumio-2 volumio[30407]: info: Volumio called home Nov 29 23:35:47 volumio-2 volumio[30407]: info: Spotify config file written Nov 29 23:35:47 volumio-2 volumio[30407]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 sudo[30563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 23:35:47 volumio-2 sudo[30563]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:47 volumio-2 systemd[1]: Stopping go-librespot Daemon... Nov 29 23:35:47 volumio-2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:47 volumio-2 systemd[1]: go-librespot-daemon.service: Succeeded. Nov 29 23:35:47 volumio-2 systemd[1]: Stopped go-librespot Daemon. Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:47 volumio-2 volumio[30407]: info: No need to fix Spotify hosts Nov 29 23:35:47 volumio-2 systemd[1]: Started go-librespot Daemon. Nov 29 23:35:47 volumio-2 go-librespot[30569]: Librespot-go daemon starting... Nov 29 23:35:47 volumio-2 sudo[30563]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:47 volumio-2 go-librespot[30569]: time="2024-11-29T23:35:47Z" level=info msg="generated new device id: 027a193c73447b17bf54ec13d012d11dbe3b02b1" Nov 29 23:35:48 volumio-2 volumio[30407]: info: Starting Shairport Sync Nov 29 23:35:48 volumio-2 volumio[30407]: info: Starting Shairport Sync Nov 29 23:35:48 volumio-2 sudo[30582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:48 volumio-2 volumio[30407]: info: Starting Shairport Sync Nov 29 23:35:48 volumio-2 sudo[30582]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:48 volumio-2 volumio[30407]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:48 volumio-2 volumio[30407]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:48 volumio-2 sudo[30584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:48 volumio-2 sudo[30584]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:48 volumio-2 sudo[30587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:48 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:48 volumio-2 sudo[30587]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:48 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:48 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:48 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:48 volumio-2 go-librespot[30569]: time="2024-11-29T23:35:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 29 23:35:48 volumio-2 go-librespot[30569]: time="2024-11-29T23:35:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 29 23:35:48 volumio-2 go-librespot[30569]: time="2024-11-29T23:35:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 29 23:35:48 volumio-2 go-librespot[30569]: time="2024-11-29T23:35:48Z" level=debug msg="zeroconf server listening on port 38041" Nov 29 23:35:48 volumio-2 volumio[30407]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:48 volumio-2 volumio[30407]: info: Output device has changed, restarting MPD Nov 29 23:35:48 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:48 volumio-2 sudo[30582]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:48 volumio-2 volumio[30407]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:48 volumio-2 sudo[30592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:48 volumio-2 sudo[30592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:48 volumio-2 sudo[30584]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:48 volumio-2 sudo[30587]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:48 volumio-2 sudo[30594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:48 volumio-2 sudo[30592]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:48 volumio-2 sudo[30594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:48 volumio-2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:48 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:48 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:48 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:48 volumio-2 volumio[30407]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:48 volumio-2 volumio[30407]: info: MPD Permissions set Nov 29 23:35:48 volumio-2 volumio[30407]: info: Shairport-Sync Started Nov 29 23:35:48 volumio-2 volumio[30407]: Error adding Membership: Error: addMembership EINVAL Nov 29 23:35:48 volumio-2 volumio[30407]: info: Shairport-Sync Started Nov 29 23:35:48 volumio-2 volumio[30407]: info: Shairport-Sync Started Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:48 volumio-2 volumio[30407]: info: Starting Shairport Sync Nov 29 23:35:48 volumio-2 sudo[30602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:48 volumio-2 sudo[30602]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:48 volumio-2 sudo[30602]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:48 volumio-2 volumio[30407]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:35:48 volumio-2 volumio[30407]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 29 23:35:48 volumio-2 sudo[30610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:35:48 volumio-2 sudo[30610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:48 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:35:48 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:35:48 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:48 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:35:48 volumio-2 sudo[30610]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:48 volumio-2 volumio[30407]: info: Shairport-Sync Started Nov 29 23:35:48 volumio-2 volumiologrotate[521]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 29 23:35:48 volumio-2 volumiologrotate[521]: ls: cannot access '2': No such file or directory Nov 29 23:35:48 volumio-2 volumio[30407]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:48 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 23:35:48 volumio-2 volumio[30407]: info: Received Get System Info Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 23:35:48 volumio-2 volumio[30407]: info: Discovery: Getting this device information Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:48 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:48 volumio-2 volumio[30407]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:48 volumio-2 volumio[30407]: info: Listing playlists Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:48 volumio-2 volumio[30407]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:49 volumio-2 volumio[30407]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:49 volumio-2 volumio[30407]: 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 Nov 29 23:35:49 volumio-2 volumio[30407]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:35:49 volumio-2 volumio[30407]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 29 23:35:49 volumio-2 volumio[30407]: info: camilladsp service started and running in background, instance 1 Nov 29 23:35:49 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:49 volumio-2 volumio[30407]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:49 volumio-2 volumio[30407]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 29 23:35:49 volumio-2 volumio[30407]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:35:49 volumio-2 volumio[30407]: info: FusionDsp loaded Nov 29 23:35:49 volumio-2 volumio[30407]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:49 volumio-2 sudo[30668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 29 23:35:49 volumio-2 sudo[30668]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:49 volumio-2 volumio[30407]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 29 23:35:49 volumio-2 volumio[30407]: info: Adding Signal Path Element [object Object] Nov 29 23:35:49 volumio-2 volumio[30407]: info: Adding fusiondspeq DSP Signal Path Element Nov 29 23:35:49 volumio-2 volumio[30407]: info: FusionDsp - ---- installed callbackRead Nov 29 23:35:49 volumio-2 volumio[30407]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:49 volumio-2 volumio[30407]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 29 23:35:49 volumio-2 volumio[30407]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 29 23:35:49 volumio-2 volumio[30407]: at onErrorNT (internal/child_process.js:465:16) Nov 29 23:35:49 volumio-2 volumio[30407]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 29 23:35:49 volumio-2 volumio[30407]: at runNextTicks (internal/process/task_queues.js:62:3) Nov 29 23:35:49 volumio-2 volumio[30407]: at listOnTimeout (internal/timers.js:523:9) Nov 29 23:35:49 volumio-2 volumio[30407]: at processTimers (internal/timers.js:497:7) { Nov 29 23:35:49 volumio-2 volumio[30407]: errno: -13, Nov 29 23:35:49 volumio-2 volumio[30407]: code: 'EACCES', Nov 29 23:35:49 volumio-2 volumio[30407]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:49 volumio-2 volumio[30407]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:35:49 volumio-2 volumio[30407]: spawnargs: [ Nov 29 23:35:49 volumio-2 volumio[30407]: '-p', Nov 29 23:35:49 volumio-2 volumio[30407]: 9876, Nov 29 23:35:49 volumio-2 volumio[30407]: '-o', Nov 29 23:35:49 volumio-2 volumio[30407]: '/tmp/camilladsp.log', Nov 29 23:35:49 volumio-2 volumio[30407]: '-l', Nov 29 23:35:49 volumio-2 volumio[30407]: 'warn', Nov 29 23:35:49 volumio-2 volumio[30407]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 29 23:35:49 volumio-2 volumio[30407]: ] Nov 29 23:35:49 volumio-2 volumio[30407]: } Nov 29 23:35:49 volumio-2 volumio[30407]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:35:49 volumio-2 sudo[30668]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:49 volumio-2 mpd[30611]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 23:35:49 volumio-2 mpd[30611]: output: No 'audio_output' defined in config file Nov 29 23:35:49 volumio-2 mpd[30611]: output: Successfully detected a sndio audio device Nov 29 23:35:49 volumio-2 mpd[30611]: zeroconf: No global port, disabling zeroconf Nov 29 23:35:49 volumio-2 systemd[1]: Started Music Player Daemon. Nov 29 23:35:49 volumio-2 sudo[30503]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:49 volumio-2 sudo[30594]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:49 volumio-2 sudo[30513]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:50 volumio-2 sudo[30682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 23:34 Nov 29 23:35:50 volumio-2 sudo[30682]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:50 volumio-2 sudo[30682]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:50 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 23:35:50 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 23:35:50 volumio-2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 29 23:35:50 volumio-2 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 29 23:35:50 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:50 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:50 volumio-2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 29 23:35:50 volumio-2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 117. Nov 29 23:35:50 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:35:50 volumio-2 systemd[1]: Stopped Volumio Backend Module. Nov 29 23:35:50 volumio-2 systemd[1]: Started Volumio Backend Module. Nov 29 23:35:50 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:35:52 volumio-2 volumio[30693]: info: ------------------------------------------- Nov 29 23:35:52 volumio-2 volumio[30693]: info: ----- Volumio3 ---- Nov 29 23:35:52 volumio-2 volumio[30693]: info: ------------------------------------------- Nov 29 23:35:52 volumio-2 volumio[30693]: info: ----- System startup ---- Nov 29 23:35:52 volumio-2 volumio[30693]: info: ------------------------------------------- Nov 29 23:35:53 volumio-2 volumio[30693]: info: MYVOLUMIO Environment detected Nov 29 23:35:53 volumio-2 volumio[30693]: info: Plugin folders cleanup Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning into folder /volumio/app/plugins/ Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category audio_interface Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category miscellanea Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category music_service Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category plugins.json Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category system_controller Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category user_interface Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning into folder /data/plugins/ Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category audio_interface Nov 29 23:35:53 volumio-2 volumio[30693]: info: Scanning category music_service Nov 29 23:35:53 volumio-2 volumio[30693]: info: Plugin folders cleanup completed Nov 29 23:35:53 volumio-2 volumio[30693]: info: ------------------------------------------- Nov 29 23:35:53 volumio-2 volumio[30693]: info: ----- Core plugins startup ---- Nov 29 23:35:53 volumio-2 volumio[30693]: info: ------------------------------------------- Nov 29 23:35:53 volumio-2 volumio[30693]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 23:35:53 volumio-2 volumio[30693]: info: Adding plugin upnp to MyMusic Plugins Nov 29 23:35:53 volumio-2 volumio[30693]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 23:35:53 volumio-2 volumio[30693]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 23:35:53 volumio-2 volumio[30693]: info: Loading plugins from folder /data/plugins/ Nov 29 23:35:53 volumio-2 volumio[30693]: info: Loading plugin "system"... Nov 29 23:35:53 volumio-2 volumio[30693]: info: Loading plugin "appearance"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "network"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Refreshing Cached IP Addresses Nov 29 23:35:54 volumio-2 sudo[30719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 23:35:54 volumio-2 sudo[30719]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:54 volumio-2 sudo[30719]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:54 volumio-2 sudo[30721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "services"... Nov 29 23:35:54 volumio-2 sudo[30721]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "alsa_controller"... Nov 29 23:35:54 volumio-2 sudo[30721]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:54 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "wizard"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "networkfs"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Starting Udev Watcher for removable devices Nov 29 23:35:54 volumio-2 volumio[30693]: info: Ignoring mount for partition: boot Nov 29 23:35:54 volumio-2 volumio[30693]: info: Ignoring mount for partition: volumio Nov 29 23:35:54 volumio-2 volumio[30693]: info: Ignoring mount for partition: volumio_data Nov 29 23:35:54 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "volumio_command_line_client"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "upnp"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: [1732923354262] Starting Upmpd Daemon Nov 29 23:35:54 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "my_music"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "mpd"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "upnp_browser"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "alarm-clock"... Nov 29 23:35:54 volumio-2 volumio[30693]: info: Loading plugin "airplay_emulation"... Nov 29 23:35:55 volumio-2 volumio[30693]: info: Starting Shairport Sync Nov 29 23:35:55 volumio-2 volumio[30693]: info: Loading plugin "last_100"... Nov 29 23:35:55 volumio-2 volumio[30693]: info: Loading plugin "webradio"... Nov 29 23:35:55 volumio-2 volumio[30693]: info: Loading plugin "i2s_dacs"... Nov 29 23:35:55 volumio-2 volumio[30693]: info: Loading plugin "volumiodiscovery"... Nov 29 23:35:55 volumio-2 volumio[30693]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:55 volumio-2 volumio[30693]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:55 volumio-2 volumio[30693]: *** WARNING *** For more information see Nov 29 23:35:55 volumio-2 node[30693]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:55 volumio-2 volumio[30693]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:55 volumio-2 volumio[30693]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:55 volumio-2 volumio[30693]: *** WARNING *** For more information see Nov 29 23:35:55 volumio-2 node[30693]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:55 volumio-2 node[30693]: *** WARNING *** For more information see Nov 29 23:35:55 volumio-2 node[30693]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:35:55 volumio-2 node[30693]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:35:55 volumio-2 node[30693]: *** WARNING *** For more information see Nov 29 23:35:55 volumio-2 volumio[30693]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 23:35:55 volumio-2 volumio[30693]: info: Discovery: Started advertising with name: Volumio 2 Nov 29 23:35:55 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:35:55 volumio-2 volumio[30693]: info: Loading plugin "spop"... Nov 29 23:35:55 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:55] [connect] Successful connection Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "outputs"... Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "albumart"... Nov 29 23:35:56 volumio-2 volumio[30693]: info: Plugin example_plugin is not enabled Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "inputs"... Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "updater_comm"... Nov 29 23:35:56 volumio-2 volumio[30693]: info: Plugin mpdemulation is not enabled Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "rest_api"... Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "websocket"... Nov 29 23:35:56 volumio-2 volumio[30693]: info: Starting Socket.io Server version 2.3.0 Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "fusiondsp"... Nov 29 23:35:56 volumio-2 volumio[30693]: Forking 3 albumart workers Nov 29 23:35:56 volumio-2 volumio[30693]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 23:35:56 volumio-2 volumio[30693]: info: Loading plugin "radio_paradise"... Nov 29 23:35:57 volumio-2 volumio[30693]: info: Applying required configuration parameters for plugin radio_paradise Nov 29 23:35:57 volumio-2 volumio[30693]: info: [1732923357075] [RadioParadise] API delay: 5 Nov 29 23:35:57 volumio-2 volumio[30693]: info: Loading i18n strings for locale en Nov 29 23:35:57 volumio-2 volumio[30693]: Updating browse sources language Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::initPlayerControls Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: Express server listening on port 3000 Nov 29 23:35:57 volumio-2 volumio[30693]: [Metrics] WebUI: 5s 478.21ms Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::resetVolumioState Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::getcurrentVolume Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:57 volumio-2 volumio[30693]: info: Volumio Network Manager: Network status updated: 1 Nov 29 23:35:57 volumio-2 volumio[30693]: Starting albumart workers Nov 29 23:35:57 volumio-2 volumio[30693]: Starting albumart workers Nov 29 23:35:57 volumio-2 volumio[30693]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 29 23:35:57 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:35:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732923355 101 Nov 29 23:35:57 volumio-2 volumio[30693]: 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: 2 Nov 29 23:35:57 volumio-2 volumio[30693]: Starting albumart workers Nov 29 23:35:57 volumio-2 volumio[30693]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 23:35:57 volumio-2 volumio[30693]: info: Reloading queue from file Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::setRepeat null single undefined Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::pushState Nov 29 23:35:57 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::setRandom null Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::pushState Nov 29 23:35:57 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:57 volumio-2 volumio[30693]: info: Setting Device type: Raspberry PI Nov 29 23:35:57 volumio-2 volumio[30693]: info: Completed loading Core Plugins Nov 29 23:35:57 volumio-2 volumio[30693]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:57 volumio-2 volumio[30693]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::pushState Nov 29 23:35:57 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreStateMachine::updateTrackBlock Nov 29 23:35:57 volumio-2 volumio[30693]: info: CorePlayQueue::getTrackBlock Nov 29 23:35:57 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:35:57 volumio-2 volumio[30693]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:58 volumio-2 volumio[30693]: info: Reading ALSA contributions from plugins. Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:58 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 23:35:58 volumio-2 volumio[30693]: info: Received Get System Info Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 23:35:58 volumio-2 volumio[30693]: info: Discovery: Getting this device information Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:58 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:58 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: Listing playlists Nov 29 23:35:58 volumio-2 volumio[30693]: info: Discovery: adding 73caacf9-cf0b-4e00-807c-e3f5ec7e4332 Nov 29 23:35:58 volumio-2 volumio[30693]: info: Discovery: Found device Volumio 2 Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioGetState Nov 29 23:35:58 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreStateMachine::pushState Nov 29 23:35:58 volumio-2 volumio[30693]: info: CorePlayQueue::getTrack 0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioPushState Nov 29 23:35:58 volumio-2 volumio[30693]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:35:58 volumio-2 volumio[30693]: info: Output device has changed, restarting MPD Nov 29 23:35:58 volumio-2 volumio[30693]: info: Output device has changed, restarting Shairport Sync Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:58 volumio-2 sudo[30795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:58 volumio-2 sudo[30795]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:58 volumio-2 sudo[30795]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:58 volumio-2 sudo[30804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:58 volumio-2 sudo[30804]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:58 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 29 23:35:58 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:58 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:58 volumio-2 volumio[30693]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: ___________ START PLUGINS ___________ Nov 29 23:35:58 volumio-2 volumio[30693]: info: ControllerMpd::onStart: Initializing MPD Nov 29 23:35:58 volumio-2 volumio[30693]: info: Creating MPD Configuration file Nov 29 23:35:58 volumio-2 sudo[30816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:35:58 volumio-2 sudo[30816]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:58 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:58 volumio-2 volumio[30693]: info: [1732923358450] CoreMusicLibrary::Adding element Media Servers Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:58 volumio-2 sudo[30816]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:58 volumio-2 sudo[30822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:35:58 volumio-2 sudo[30822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:58 volumio-2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 29 23:35:58 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:35:58 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:35:58 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:35:58 volumio-2 volumio[30693]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:58 volumio-2 volumio[30693]: info: [1732923358605] CoreMusicLibrary::Adding element Last_100 Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:58 volumio-2 volumio[30693]: info: [1732923358611] CoreMusicLibrary::Adding element Webradio Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:58 volumio-2 volumio[30693]: info: Initializing BBC Radios Nov 29 23:35:58 volumio-2 sudo[30828]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:35:58 volumio-2 sudo[30828]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:58 volumio-2 sudo[30828]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: Creating Spotify config file Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: Loading i18n strings for locale en Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:58 volumio-2 volumio[30693]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:35:58 volumio-2 volumio[30693]: info: [1732923358749] CoreMusicLibrary::Adding element Radio Paradise Nov 29 23:35:58 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:35:58 volumio-2 volumio[30693]: Cannot find translation for source Radio Paradise Nov 29 23:35:58 volumio-2 volumio[30693]: info: Volumio Calling Home Nov 29 23:35:58 volumio-2 volumio[30693]: info: Preparing to generate the ALSA configuration file Nov 29 23:35:58 volumio-2 volumio[30693]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:35:58 volumio-2 volumio[30693]: info: Reading ALSA contributions from plugins. Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 23:35:59 volumio-2 volumio[30693]: info: MPD Permissions set Nov 29 23:35:59 volumio-2 volumio[30693]: info: MPD Permissions set Nov 29 23:35:59 volumio-2 volumio[30693]: info: Volumio called home Nov 29 23:35:59 volumio-2 volumio[30693]: info: Spotify config file written Nov 29 23:35:59 volumio-2 volumio[30693]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 29 23:35:59 volumio-2 sudo[30848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 23:35:59 volumio-2 sudo[30848]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 systemd[1]: Stopping go-librespot Daemon... Nov 29 23:35:59 volumio-2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 29 23:35:59 volumio-2 systemd[1]: go-librespot-daemon.service: Succeeded. Nov 29 23:35:59 volumio-2 systemd[1]: Stopped go-librespot Daemon. Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:35:59 volumio-2 volumio[30693]: info: No need to fix Spotify hosts Nov 29 23:35:59 volumio-2 systemd[1]: Started go-librespot Daemon. Nov 29 23:35:59 volumio-2 go-librespot[30854]: Librespot-go daemon starting... Nov 29 23:35:59 volumio-2 sudo[30848]: pam_unix(sudo:session): session closed for user root Nov 29 23:35:59 volumio-2 go-librespot[30854]: time="2024-11-29T23:35:59Z" level=info msg="generated new device id: 70439f5a14f76d079eabe05ba79dc6541dc826ab" Nov 29 23:35:59 volumio-2 go-librespot[30854]: time="2024-11-29T23:35:59Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 29 23:35:59 volumio-2 go-librespot[30854]: time="2024-11-29T23:35:59Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 29 23:35:59 volumio-2 go-librespot[30854]: time="2024-11-29T23:35:59Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 29 23:35:59 volumio-2 go-librespot[30854]: time="2024-11-29T23:35:59Z" level=debug msg="zeroconf server listening on port 45187" Nov 29 23:36:00 volumio-2 mpd[30834]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 23:36:00 volumio-2 mpd[30834]: output: No 'audio_output' defined in config file Nov 29 23:36:00 volumio-2 mpd[30834]: output: Successfully detected a sndio audio device Nov 29 23:36:00 volumio-2 mpd[30834]: zeroconf: No global port, disabling zeroconf Nov 29 23:36:00 volumio-2 systemd[1]: Started Music Player Daemon. Nov 29 23:36:00 volumio-2 sudo[30804]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:00 volumio-2 sudo[30822]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:00 volumio-2 volumio[30693]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:36:00 volumio-2 volumio[30693]: TypeError: Cannot read property 'off' of undefined Nov 29 23:36:00 volumio-2 volumio[30693]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Nov 29 23:36:00 volumio-2 volumio[30693]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 29 23:36:00 volumio-2 volumio[30693]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 29 23:36:00 volumio-2 volumio[30693]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1551:29) Nov 29 23:36:00 volumio-2 volumio[30693]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Nov 29 23:36:00 volumio-2 volumio[30693]: at Socket.emit (events.js:315:20) Nov 29 23:36:00 volumio-2 volumio[30693]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 29 23:36:00 volumio-2 volumio[30693]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 29 23:36:00 volumio-2 volumio[30693]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:36:00 volumio-2 sudo[30882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 23:35 Nov 29 23:36:00 volumio-2 sudo[30882]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:01 volumio-2 sudo[30882]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:01 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:36:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 23:36:01 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:36:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 23:36:01 volumio-2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 29 23:36:01 volumio-2 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 29 23:36:01 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:36:01 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:36:01 volumio-2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 29 23:36:01 volumio-2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 118. Nov 29 23:36:01 volumio-2 systemd[1]: Stopped Volumio Backend Module. Nov 29 23:36:01 volumio-2 systemd[1]: Started Volumio Backend Module. Nov 29 23:36:01 volumio-2 systemd[1]: Started dynamicswap service. Nov 29 23:36:01 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 29 23:36:03 volumio-2 volumio[30895]: info: ------------------------------------------- Nov 29 23:36:03 volumio-2 volumio[30895]: info: ----- Volumio3 ---- Nov 29 23:36:03 volumio-2 volumio[30895]: info: ------------------------------------------- Nov 29 23:36:03 volumio-2 volumio[30895]: info: ----- System startup ---- Nov 29 23:36:03 volumio-2 volumio[30895]: info: ------------------------------------------- Nov 29 23:36:03 volumio-2 volumio[30895]: info: MYVOLUMIO Environment detected Nov 29 23:36:03 volumio-2 volumio[30895]: info: Plugin folders cleanup Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning into folder /volumio/app/plugins/ Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category audio_interface Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category miscellanea Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category music_service Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category plugins.json Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category system_controller Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category user_interface Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning into folder /data/plugins/ Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category audio_interface Nov 29 23:36:03 volumio-2 volumio[30895]: info: Scanning category music_service Nov 29 23:36:03 volumio-2 volumio[30895]: info: Plugin folders cleanup completed Nov 29 23:36:03 volumio-2 volumio[30895]: info: ------------------------------------------- Nov 29 23:36:03 volumio-2 volumio[30895]: info: ----- Core plugins startup ---- Nov 29 23:36:03 volumio-2 volumio[30895]: info: ------------------------------------------- Nov 29 23:36:03 volumio-2 volumio[30895]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 23:36:03 volumio-2 volumio[30895]: info: Adding plugin upnp to MyMusic Plugins Nov 29 23:36:03 volumio-2 volumio[30895]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 23:36:03 volumio-2 volumio[30895]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 23:36:03 volumio-2 volumio[30895]: info: Loading plugins from folder /data/plugins/ Nov 29 23:36:03 volumio-2 volumio[30895]: info: Loading plugin "system"... Nov 29 23:36:03 volumio-2 volumio[30895]: info: Loading plugin "appearance"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "network"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: Refreshing Cached IP Addresses Nov 29 23:36:04 volumio-2 sudo[30922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 23:36:04 volumio-2 sudo[30922]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:04 volumio-2 sudo[30922]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:04 volumio-2 sudo[30924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 23:36:04 volumio-2 sudo[30924]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "services"... Nov 29 23:36:04 volumio-2 sudo[30924]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "alsa_controller"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "wizard"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "networkfs"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: Starting Udev Watcher for removable devices Nov 29 23:36:04 volumio-2 volumio[30895]: info: Ignoring mount for partition: boot Nov 29 23:36:04 volumio-2 volumio[30895]: info: Ignoring mount for partition: volumio Nov 29 23:36:04 volumio-2 volumio[30895]: info: Ignoring mount for partition: volumio_data Nov 29 23:36:04 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "volumio_command_line_client"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "upnp"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: [1732923364988] Starting Upmpd Daemon Nov 29 23:36:04 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "my_music"... Nov 29 23:36:04 volumio-2 volumio[30895]: info: Loading plugin "mpd"... Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "upnp_browser"... Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "alarm-clock"... Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "airplay_emulation"... Nov 29 23:36:05 volumio-2 volumio[30895]: info: Starting Shairport Sync Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "last_100"... Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "webradio"... Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "i2s_dacs"... Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "volumiodiscovery"... Nov 29 23:36:05 volumio-2 volumio[30895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:36:05 volumio-2 volumio[30895]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:36:05 volumio-2 volumio[30895]: *** WARNING *** For more information see Nov 29 23:36:05 volumio-2 node[30895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 23:36:05 volumio-2 volumio[30895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:36:05 volumio-2 volumio[30895]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:36:05 volumio-2 volumio[30895]: *** WARNING *** For more information see Nov 29 23:36:05 volumio-2 node[30895]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:36:05 volumio-2 node[30895]: *** WARNING *** For more information see Nov 29 23:36:05 volumio-2 node[30895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 23:36:05 volumio-2 node[30895]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 23:36:05 volumio-2 node[30895]: *** WARNING *** For more information see Nov 29 23:36:05 volumio-2 volumio[30895]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 23:36:05 volumio-2 volumio[30895]: info: Discovery: Started advertising with name: Volumio 2 Nov 29 23:36:05 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 23:36:05 volumio-2 volumio[30895]: info: Loading plugin "spop"... Nov 29 23:36:06 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:36:06] [connect] Successful connection Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "outputs"... Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "albumart"... Nov 29 23:36:07 volumio-2 volumio[30895]: info: Plugin example_plugin is not enabled Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "inputs"... Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "updater_comm"... Nov 29 23:36:07 volumio-2 volumio[30895]: info: Plugin mpdemulation is not enabled Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "rest_api"... Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "websocket"... Nov 29 23:36:07 volumio-2 volumio[30895]: info: Starting Socket.io Server version 2.3.0 Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "fusiondsp"... Nov 29 23:36:07 volumio-2 volumio[30895]: Forking 3 albumart workers Nov 29 23:36:07 volumio-2 volumio[30895]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading plugin "radio_paradise"... Nov 29 23:36:07 volumio-2 volumio[30895]: info: Applying required configuration parameters for plugin radio_paradise Nov 29 23:36:07 volumio-2 volumio[30895]: info: [1732923367890] [RadioParadise] API delay: 5 Nov 29 23:36:07 volumio-2 volumio[30895]: info: Loading i18n strings for locale en Nov 29 23:36:07 volumio-2 volumio[30895]: Updating browse sources language Nov 29 23:36:07 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::initPlayerControls Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: Express server listening on port 3000 Nov 29 23:36:08 volumio-2 volumio[30895]: [Metrics] WebUI: 5s 575.57ms Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::resetVolumioState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::getcurrentVolume Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:36:08 volumio-2 volumio[30895]: Starting albumart workers Nov 29 23:36:08 volumio-2 volumio[30895]: info: Volumio Network Manager: Network status updated: 1 Nov 29 23:36:08 volumio-2 volumio[30895]: Starting albumart workers Nov 29 23:36:08 volumio-2 volumio[30895]: Starting albumart workers Nov 29 23:36:08 volumio-2 volumio[30895]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 29 23:36:08 volumio-2 volumio-remote-updater[520]: [2024-11-29 23:36:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732923366 101 Nov 29 23:36:08 volumio-2 volumio[30895]: 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: 2 Nov 29 23:36:08 volumio-2 volumio[30895]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.162 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: Reloading queue from file Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::setRepeat null single undefined Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::pushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CorePlayQueue::getTrack 0 Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioPushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::setRandom null Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::pushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CorePlayQueue::getTrack 0 Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioPushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: Setting Device type: Raspberry PI Nov 29 23:36:08 volumio-2 volumio[30895]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::pushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CorePlayQueue::getTrack 0 Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioPushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::updateTrackBlock Nov 29 23:36:08 volumio-2 volumio[30895]: info: CorePlayQueue::getTrackBlock Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 23:36:08 volumio-2 volumio[30895]: info: Completed loading Core Plugins Nov 29 23:36:08 volumio-2 volumio[30895]: info: Preparing to generate the ALSA configuration file Nov 29 23:36:08 volumio-2 volumio[30895]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:36:08 volumio-2 volumio[30895]: info: Reading ALSA contributions from plugins. Nov 29 23:36:08 volumio-2 volumio[30895]: info: Discovery: adding 73caacf9-cf0b-4e00-807c-e3f5ec7e4332 Nov 29 23:36:08 volumio-2 volumio[30895]: info: Discovery: Found device Volumio 2 Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioGetState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CorePlayQueue::getTrack 0 Nov 29 23:36:08 volumio-2 volumio[30895]: info: VolumeController:: Volume=0 Mute =false Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreStateMachine::pushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: CorePlayQueue::getTrack 0 Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioPushState Nov 29 23:36:08 volumio-2 volumio[30895]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:36:08 volumio-2 volumio[30895]: info: Output device has changed, restarting MPD Nov 29 23:36:08 volumio-2 volumio[30895]: info: Output device has changed, restarting Shairport Sync Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:08 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:08 volumio-2 sudo[30989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:36:08 volumio-2 sudo[30989]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:08 volumio-2 sudo[30989]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:08 volumio-2 sudo[30991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:36:08 volumio-2 sudo[30991]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:09 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 29 23:36:09 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:36:09 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:36:09 volumio-2 volumio[30895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:36:09 volumio-2 volumio[30895]: info: ___________ START PLUGINS ___________ Nov 29 23:36:09 volumio-2 volumio[30895]: info: ControllerMpd::onStart: Initializing MPD Nov 29 23:36:09 volumio-2 volumio[30895]: info: Creating MPD Configuration file Nov 29 23:36:09 volumio-2 sudo[31007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:36:09 volumio-2 sudo[31007]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:36:09 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:36:09 volumio-2 sudo[31007]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:09 volumio-2 volumio[30895]: info: [1732923369142] CoreMusicLibrary::Adding element Media Servers Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:36:09 volumio-2 sudo[31013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:36:09 volumio-2 sudo[31013]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:09 volumio-2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 29 23:36:09 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:36:09 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:36:09 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:36:09 volumio-2 volumio[30895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:36:09 volumio-2 volumio[30895]: info: [1732923369329] CoreMusicLibrary::Adding element Last_100 Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:36:09 volumio-2 volumio[30895]: info: [1732923369333] CoreMusicLibrary::Adding element Webradio Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:36:09 volumio-2 volumio[30895]: info: Initializing BBC Radios Nov 29 23:36:09 volumio-2 sudo[31031]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:36:09 volumio-2 sudo[31031]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:09 volumio-2 sudo[31031]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: Creating Spotify config file Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: Loading i18n strings for locale en Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 23:36:09 volumio-2 volumio[30895]: info: [1732923369470] CoreMusicLibrary::Adding element Radio Paradise Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 23:36:09 volumio-2 volumio[30895]: Cannot find translation for source Radio Paradise Nov 29 23:36:09 volumio-2 volumio[30895]: info: Volumio Calling Home Nov 29 23:36:09 volumio-2 volumio[30895]: info: Preparing to generate the ALSA configuration file Nov 29 23:36:09 volumio-2 volumio[30895]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 23:36:09 volumio-2 volumio[30895]: info: Reading ALSA contributions from plugins. Nov 29 23:36:09 volumio-2 volumio[30895]: info: MPD Permissions set Nov 29 23:36:09 volumio-2 volumio[30895]: info: MPD Permissions set Nov 29 23:36:09 volumio-2 volumio[30895]: info: Spotify config file written Nov 29 23:36:09 volumio-2 volumio[30895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 29 23:36:09 volumio-2 sudo[31051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 23:36:09 volumio-2 sudo[31051]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:09 volumio-2 volumio[30895]: info: Volumio called home Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 systemd[1]: Stopping go-librespot Daemon... Nov 29 23:36:09 volumio-2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 29 23:36:09 volumio-2 systemd[1]: go-librespot-daemon.service: Succeeded. Nov 29 23:36:09 volumio-2 systemd[1]: Stopped go-librespot Daemon. Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:09 volumio-2 volumio[30895]: info: No need to fix Spotify hosts Nov 29 23:36:09 volumio-2 systemd[1]: Started go-librespot Daemon. Nov 29 23:36:09 volumio-2 go-librespot[31057]: Librespot-go daemon starting... Nov 29 23:36:09 volumio-2 sudo[31051]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:09 volumio-2 go-librespot[31057]: time="2024-11-29T23:36:09Z" level=info msg="generated new device id: ef73d6e048ae931825948e49b73bcad79c91c9bb" Nov 29 23:36:09 volumio-2 volumio[30895]: info: Starting Shairport Sync Nov 29 23:36:10 volumio-2 volumio[30895]: info: Starting Shairport Sync Nov 29 23:36:10 volumio-2 sudo[31069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:36:10 volumio-2 volumio[30895]: info: Starting Shairport Sync Nov 29 23:36:10 volumio-2 sudo[31069]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:10 volumio-2 sudo[31071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:36:10 volumio-2 sudo[31071]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:10 volumio-2 volumio[30895]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:36:10 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:36:10 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:36:10 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::volumioGetState Nov 29 23:36:10 volumio-2 volumio[30895]: info: CorePlayQueue::getTrack 0 Nov 29 23:36:10 volumio-2 sudo[31076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:36:10 volumio-2 sudo[31076]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:10 volumio-2 volumio[30895]: info: Asound.conf file unchanged, so no further update is needed Nov 29 23:36:10 volumio-2 volumio[30895]: info: Output device has changed, restarting MPD Nov 29 23:36:10 volumio-2 go-librespot[31057]: time="2024-11-29T23:36:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 29 23:36:10 volumio-2 go-librespot[31057]: time="2024-11-29T23:36:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 29 23:36:10 volumio-2 go-librespot[31057]: time="2024-11-29T23:36:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 29 23:36:10 volumio-2 go-librespot[31057]: time="2024-11-29T23:36:10Z" level=debug msg="zeroconf server listening on port 40545" Nov 29 23:36:10 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:36:10 volumio-2 sudo[31069]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:10 volumio-2 sudo[31071]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:10 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:36:10 volumio-2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 29 23:36:10 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:36:10 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:36:10 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:36:10 volumio-2 volumio[30895]: info: Output device has changed, restarting Shairport Sync Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:10 volumio-2 sudo[31076]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:10 volumio-2 sudo[31085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 23:36:10 volumio-2 sudo[31085]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:10 volumio-2 sudo[31084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 23:36:10 volumio-2 sudo[31084]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:10 volumio-2 sudo[31084]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:10 volumio-2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 23:36:10 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 29 23:36:10 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 29 23:36:10 volumio-2 volumio[30895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 23:36:10 volumio-2 volumio[30895]: info: MPD Permissions set Nov 29 23:36:10 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 29 23:36:10 volumio-2 volumio[30895]: info: Shairport-Sync Started Nov 29 23:36:10 volumio-2 volumio[30895]: Error adding Membership: Error: addMembership EINVAL Nov 29 23:36:10 volumio-2 volumio[30895]: info: Shairport-Sync Started Nov 29 23:36:10 volumio-2 volumio[30895]: info: Shairport-Sync Started Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:10 volumio-2 volumio[30895]: info: Starting Shairport Sync Nov 29 23:36:10 volumio-2 volumio[30895]: info: Could not write mpd.conf:Error: ENOSPC: no space left on device, write Nov 29 23:36:10 volumio-2 sudo[31100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 23:36:10 volumio-2 sudo[31100]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:10 volumio-2 volumio[30895]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 29 23:36:10 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 23:36:10 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 29 23:36:10 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 23:36:10 volumio-2 sudo[31093]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 23:36:10 volumio-2 sudo[31093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:10 volumio-2 sudo[31093]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:10 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 23:36:10 volumio-2 sudo[31100]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:10 volumio-2 volumio[30895]: info: Shairport-Sync Started Nov 29 23:36:11 volumio-2 volumio[30895]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:36:11 volumio-2 volumio[30895]: 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 Nov 29 23:36:11 volumio-2 volumio[30895]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 29 23:36:11 volumio-2 volumio[30895]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 29 23:36:11 volumio-2 volumio[30895]: info: camilladsp service started and running in background, instance 1 Nov 29 23:36:11 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 23:36:11 volumio-2 volumio[30895]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:36:11 volumio-2 volumio[30895]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 29 23:36:11 volumio-2 volumio[30895]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 23:36:11 volumio-2 volumio[30895]: info: FusionDsp loaded Nov 29 23:36:11 volumio-2 volumio[30895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 23:36:11 volumio-2 sudo[31116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 29 23:36:11 volumio-2 sudo[31116]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 23:36:11 volumio-2 volumio[30895]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 29 23:36:11 volumio-2 volumio[30895]: info: Adding Signal Path Element [object Object] Nov 29 23:36:11 volumio-2 volumio[30895]: info: Adding fusiondspeq DSP Signal Path Element Nov 29 23:36:11 volumio-2 volumio[30895]: info: FusionDsp - ---- installed callbackRead Nov 29 23:36:11 volumio-2 volumio[30895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:36:11 volumio-2 sudo[31116]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:11 volumio-2 volumio[30895]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 29 23:36:11 volumio-2 volumio[30895]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 29 23:36:11 volumio-2 volumio[30895]: at onErrorNT (internal/child_process.js:465:16) Nov 29 23:36:11 volumio-2 volumio[30895]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 29 23:36:11 volumio-2 volumio[30895]: errno: -13, Nov 29 23:36:11 volumio-2 volumio[30895]: code: 'EACCES', Nov 29 23:36:11 volumio-2 volumio[30895]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:36:11 volumio-2 volumio[30895]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 23:36:11 volumio-2 volumio[30895]: spawnargs: [ Nov 29 23:36:11 volumio-2 volumio[30895]: '-p', Nov 29 23:36:11 volumio-2 volumio[30895]: 9876, Nov 29 23:36:11 volumio-2 volumio[30895]: '-o', Nov 29 23:36:11 volumio-2 volumio[30895]: '/tmp/camilladsp.log', Nov 29 23:36:11 volumio-2 volumio[30895]: '-l', Nov 29 23:36:11 volumio-2 volumio[30895]: 'warn', Nov 29 23:36:11 volumio-2 volumio[30895]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 29 23:36:11 volumio-2 volumio[30895]: ] Nov 29 23:36:11 volumio-2 volumio[30895]: } Nov 29 23:36:11 volumio-2 volumio[30895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 23:36:11 volumio-2 mpd[31104]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 23:36:11 volumio-2 mpd[31104]: output: No 'audio_output' defined in config file Nov 29 23:36:11 volumio-2 mpd[31104]: output: Successfully detected a sndio audio device Nov 29 23:36:11 volumio-2 mpd[31104]: zeroconf: No global port, disabling zeroconf Nov 29 23:36:11 volumio-2 systemd[1]: Started Music Player Daemon. Nov 29 23:36:11 volumio-2 sudo[31085]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:11 volumio-2 sudo[31013]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:11 volumio-2 sudo[30991]: pam_unix(sudo:session): session closed for user root Nov 29 23:36:12 volumio-2 sudo[31130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 23:35 Nov 29 23:36:12 volumio-2 sudo[31130]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"