-- Logs begin at Thu 2019-02-14 18:11:58 AWST, end at Sun 2025-05-25 03:01:25 AWST. -- May 25 03:00:02 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:10 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:10 volumio wpa_supplicant[1054]: wlan0: CTRL-EVENT-DISCONNECTED bssid=12:75:2f:2e:92:1d reason=0 locally_generated=1 May 25 03:00:10 volumio dhcpcd[1085]: wlan0: carrier lost May 25 03:00:10 volumio wpa_supplicant[1054]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 25 03:00:10 volumio wpa_supplicant[1054]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=AU May 25 03:00:10 volumio avahi-daemon[600]: Withdrawing address record for 192.168.0.248 on wlan0. May 25 03:00:10 volumio avahi-daemon[600]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.248. May 25 03:00:10 volumio avahi-daemon[600]: Interface wlan0.IPv4 no longer relevant for mDNS. May 25 03:00:10 volumio dhcpcd[1085]: wlan0: deleting route to 192.168.0.0/24 May 25 03:00:10 volumio dhcpcd[1085]: wlan0: deleting default route via 192.168.0.1 May 25 03:00:10 volumio volumio[1389]: info: Discovery: A device disappeared from network May 25 03:00:10 volumio volumio[1389]: info: Discovery: Device volumio disappeared from network May 25 03:00:12 volumio ntpd[781]: Deleting interface #3 wlan0, 192.168.0.248#123, interface stats: received=1065, sent=1065, dropped=0, active_time=52273 secs May 25 03:00:12 volumio ntpd[781]: 103.165.180.123 local addr 192.168.0.248 -> May 25 03:00:12 volumio ntpd[781]: 110.232.114.22 local addr 192.168.0.248 -> May 25 03:00:12 volumio ntpd[781]: 162.159.200.1 local addr 192.168.0.248 -> May 25 03:00:12 volumio ntpd[781]: 159.196.178.7 local addr 192.168.0.248 -> May 25 03:00:12 volumio ntpd[781]: 119.18.6.37 local addr 192.168.0.248 -> May 25 03:00:12 volumio ntpd[781]: 159.196.247.174 local addr 192.168.0.248 -> May 25 03:00:12 volumio ntpd[781]: 162.159.200.123 local addr 192.168.0.248 -> May 25 03:00:12 volumio ntpd[781]: 203.23.237.200 local addr 192.168.0.248 -> May 25 03:00:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:00:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:00:14 volumio volumio[1389]: info: Volumio Network Manager: Network status updated: 0 May 25 03:00:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:00:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:00:15 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:15 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:15 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:00:20 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:25 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:25 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:00:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:00:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:00:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:00:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:00:30 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:35 volumio volumio[1389]: info: Discovery: Remote connection error: 192.168.0.108 May 25 03:00:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:00:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:00:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:00:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:00:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:00:38 volumio volumio[1389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:38 volumio volumio[1389]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:00:38 volumio volumio[1389]: at doSend (dgram.js:692:16) May 25 03:00:38 volumio volumio[1389]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:00:38 volumio volumio[1389]: at afterDns (dgram.js:638:5) May 25 03:00:38 volumio volumio[1389]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:00:38 volumio volumio[1389]: errno: -101, May 25 03:00:38 volumio volumio[1389]: code: 'ENETUNREACH', May 25 03:00:38 volumio volumio[1389]: syscall: 'send', May 25 03:00:38 volumio volumio[1389]: address: '255.255.255.255', May 25 03:00:38 volumio volumio[1389]: port: 3483 May 25 03:00:38 volumio volumio[1389]: } May 25 03:00:38 volumio volumio[1389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:39 volumio sudo[23833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 02:59 May 25 03:00:39 volumio sudo[23833]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:39 volumio sudo[23833]: pam_unix(sudo:session): session closed for user root May 25 03:00:39 volumio volumio-remote-updater[605]: [2025-05-25 03:00:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 25 03:00:39 volumio volumio-remote-updater[605]: [2025-05-25 03:00:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 25 03:00:39 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:00:39 volumio wpa_supplicant[1054]: wlan0: Failed to initiate sched scan May 25 03:00:39 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:00:39 volumio systemd[1]: Started dynamicswap service. May 25 03:00:39 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:39 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:00:39 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 25 03:00:39 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:00:39 volumio systemd[1]: Started Volumio Backend Module. May 25 03:00:39 volumio systemd[1]: Started dynamicswap service. May 25 03:00:39 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:40 volumio volumio[23847]: info: ------------------------------------------- May 25 03:00:40 volumio volumio[23847]: info: ----- Volumio3 ---- May 25 03:00:40 volumio volumio[23847]: info: ------------------------------------------- May 25 03:00:40 volumio volumio[23847]: info: ----- System startup ---- May 25 03:00:40 volumio volumio[23847]: info: ------------------------------------------- May 25 03:00:40 volumio volumio[23847]: info: MYVOLUMIO Environment detected May 25 03:00:40 volumio volumio[23847]: info: Plugin folders cleanup May 25 03:00:40 volumio volumio[23847]: info: Scanning into folder /volumio/app/plugins/ May 25 03:00:40 volumio volumio[23847]: info: Scanning category audio_interface May 25 03:00:40 volumio volumio[23847]: info: Scanning category miscellanea May 25 03:00:40 volumio volumio[23847]: info: Scanning category music_service May 25 03:00:40 volumio volumio[23847]: info: Scanning category plugins.json May 25 03:00:40 volumio volumio[23847]: info: Scanning category system_controller May 25 03:00:40 volumio volumio[23847]: info: Scanning category user_interface May 25 03:00:40 volumio volumio[23847]: info: Scanning into folder /data/plugins/ May 25 03:00:40 volumio volumio[23847]: info: Scanning category music_service May 25 03:00:40 volumio volumio[23847]: info: Scanning category user_interface May 25 03:00:40 volumio volumio[23847]: info: Plugin folders cleanup completed May 25 03:00:40 volumio volumio[23847]: info: ------------------------------------------- May 25 03:00:40 volumio volumio[23847]: info: ----- Core plugins startup ---- May 25 03:00:40 volumio volumio[23847]: info: ------------------------------------------- May 25 03:00:40 volumio volumio[23847]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:00:40 volumio volumio[23847]: info: Adding plugin upnp to MyMusic Plugins May 25 03:00:40 volumio volumio[23847]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:00:40 volumio volumio[23847]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:00:40 volumio volumio[23847]: info: Loading plugins from folder /data/plugins/ May 25 03:00:40 volumio volumio[23847]: info: Loading plugin "system"... May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "appearance"... May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "network"... May 25 03:00:41 volumio volumio[23847]: info: Refreshing Cached IP Addresses May 25 03:00:41 volumio sudo[23874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:00:41 volumio sudo[23874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:41 volumio sudo[23874]: pam_unix(sudo:session): session closed for user root May 25 03:00:41 volumio sudo[23876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:00:41 volumio sudo[23876]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:41 volumio sudo[23876]: pam_unix(sudo:session): session closed for user root May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "services"... May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "alsa_controller"... May 25 03:00:41 volumio sudo[23885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:00:41 volumio sudo[23885]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:41 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "wizard"... May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "networkfs"... May 25 03:00:41 volumio volumio[23847]: info: Starting Udev Watcher for removable devices May 25 03:00:41 volumio volumio[23847]: info: Ignoring mount for partition: boot May 25 03:00:41 volumio volumio[23847]: info: Ignoring mount for partition: volumio May 25 03:00:41 volumio volumio[23847]: info: Ignoring mount for partition: volumio_data May 25 03:00:41 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "volumio_command_line_client"... May 25 03:00:41 volumio volumio[23847]: info: Plugin upnp is not enabled May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "my_music"... May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "mpd"... May 25 03:00:41 volumio volumio[23847]: info: Plugin upnp_browser is not enabled May 25 03:00:41 volumio volumio[23847]: info: Loading plugin "alarm-clock"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "airplay_emulation"... May 25 03:00:42 volumio volumio[23847]: info: Starting Shairport Sync May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "last_100"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "webradio"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "i2s_dacs"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "volumiodiscovery"... May 25 03:00:42 volumio volumio[23847]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:42 volumio volumio[23847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:42 volumio volumio[23847]: *** WARNING *** For more information see May 25 03:00:42 volumio node[23847]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:42 volumio volumio[23847]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:42 volumio volumio[23847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:42 volumio volumio[23847]: *** WARNING *** For more information see May 25 03:00:42 volumio node[23847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:42 volumio node[23847]: *** WARNING *** For more information see May 25 03:00:42 volumio node[23847]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:42 volumio node[23847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:42 volumio node[23847]: *** WARNING *** For more information see May 25 03:00:42 volumio volumio[23847]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:00:42 volumio volumio[23847]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:00:42 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "squeezelite_mc"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "outputs"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "albumart"... May 25 03:00:42 volumio volumio[23847]: info: Plugin example_plugin is not enabled May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "inputs"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "updater_comm"... May 25 03:00:42 volumio volumio[23847]: info: Plugin mpdemulation is not enabled May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "rest_api"... May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "websocket"... May 25 03:00:42 volumio volumio[23847]: info: Starting Socket.io Server version 2.3.0 May 25 03:00:42 volumio volumio[23847]: info: Loading plugin "Systeminfo"... May 25 03:00:42 volumio volumio[23847]: Forking 3 albumart workers May 25 03:00:43 volumio volumio[23847]: info: Loading i18n strings for locale en May 25 03:00:43 volumio volumio[23847]: Updating browse sources language May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::initPlayerControls May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:00:43 volumio volumio[23847]: Express server listening on port 3000 May 25 03:00:43 volumio volumio[23847]: [Metrics] WebUI: 3s 28.12ms May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::resetVolumioState May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::getcurrentVolume May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:43 volumio volumio[23847]: info: Volumio Network Manager: Network status updated: 0 May 25 03:00:43 volumio volumio[23847]: Starting albumart workers May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: Reloading queue from file May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::setRepeat null single undefined May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::pushState May 25 03:00:43 volumio volumio[23847]: info: CorePlayQueue::getTrack 0 May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::volumioPushState May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::setRandom null May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::pushState May 25 03:00:43 volumio volumio[23847]: info: CorePlayQueue::getTrack 0 May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::volumioPushState May 25 03:00:43 volumio volumio[23847]: Starting albumart workers May 25 03:00:43 volumio volumio[23847]: info: Setting Device type: Raspberry PI May 25 03:00:43 volumio volumio[23847]: info: Completed loading Core Plugins May 25 03:00:43 volumio volumio[23847]: info: Preparing to generate the ALSA configuration file May 25 03:00:43 volumio volumio[23847]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::pushState May 25 03:00:43 volumio volumio[23847]: info: CorePlayQueue::getTrack 0 May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::volumioPushState May 25 03:00:43 volumio volumio[23847]: info: CoreStateMachine::updateTrackBlock May 25 03:00:43 volumio volumio[23847]: info: CorePlayQueue::getTrackBlock May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:43 volumio volumio[23847]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:00:43 volumio volumio[23847]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:00:43 volumio volumio[23847]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:00:43 volumio volumio[23847]: info: Asound.conf file unchanged, so no further update is needed May 25 03:00:43 volumio volumio[23847]: info: Output device has changed, restarting MPD May 25 03:00:43 volumio volumio[23847]: Starting albumart workers May 25 03:00:43 volumio volumio[23847]: info: Output device has changed, restarting Shairport Sync May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:43 volumio sudo[23943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:00:43 volumio sudo[23943]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:43 volumio sudo[23943]: pam_unix(sudo:session): session closed for user root May 25 03:00:43 volumio sudo[23945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:00:43 volumio sudo[23945]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:43 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:00:43 volumio systemd[1]: mpd.service: Succeeded. May 25 03:00:43 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:00:43 volumio volumio[23847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:43 volumio volumio[23847]: info: ___________ START PLUGINS ___________ May 25 03:00:43 volumio volumio[23847]: info: ControllerMpd::onStart: Initializing MPD May 25 03:00:43 volumio volumio[23847]: info: Creating MPD Configuration file May 25 03:00:43 volumio systemd[1]: Starting Music Player Daemon... May 25 03:00:43 volumio sudo[23953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:00:43 volumio sudo[23953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:43 volumio sudo[23953]: pam_unix(sudo:session): session closed for user root May 25 03:00:43 volumio sudo[23955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:00:43 volumio sudo[23955]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:43 volumio sudo[23951]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:00:43 volumio sudo[23951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:43 volumio sudo[23951]: pam_unix(sudo:session): session closed for user root May 25 03:00:43 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 25 03:00:43 volumio systemd[1]: mpd.service: Succeeded. May 25 03:00:43 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:00:43 volumio volumio[23847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:43 volumio volumio[23847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:00:43 volumio systemd[1]: Starting Music Player Daemon... May 25 03:00:43 volumio volumio[23847]: info: [1748113243918] CoreMusicLibrary::Adding element Last_100 May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:00:43 volumio volumio[23847]: info: [1748113243926] CoreMusicLibrary::Adding element Webradio May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:43 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:43 volumio volumio[23847]: info: Initializing BBC Radios May 25 03:00:44 volumio sudo[23961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:00:44 volumio sudo[23961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:44 volumio sudo[23961]: pam_unix(sudo:session): session closed for user root May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: [squeezelite_mc] Starting proxy server... May 25 03:00:44 volumio volumio[23847]: info: Volumio Calling Home May 25 03:00:44 volumio sudo[23885]: pam_unix(sudo:session): session closed for user root May 25 03:00:44 volumio volumio[23847]: info: [squeezelite_mc] Proxy server started on port 36697 May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:44 volumio volumio[23847]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:00:44 volumio volumio[23847]: info: Discovery: Found device VOLUMIO May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::volumioGetState May 25 03:00:44 volumio volumio[23847]: info: CorePlayQueue::getTrack 0 May 25 03:00:44 volumio volumio-remote-updater[605]: [2025-05-25 03:00:44] [connect] Successful connection May 25 03:00:44 volumio volumio[23847]: info: MPD Permissions set May 25 03:00:44 volumio volumio[23847]: info: MPD Permissions set May 25 03:00:44 volumio volumio[23847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 25 03:00:44 volumio volumio-remote-updater[605]: [2025-05-25 03:00:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748113244 101 May 25 03:00:44 volumio volumio[23847]: 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 May 25 03:00:44 volumio volumio[23847]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:44 volumio volumio[23847]: info: CoreStateMachine::pushState May 25 03:00:44 volumio volumio[23847]: info: CorePlayQueue::getTrack 0 May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::volumioPushState May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:44 volumio volumio[23847]: info: CoreStateMachine::pushState May 25 03:00:44 volumio volumio[23847]: info: CorePlayQueue::getTrack 0 May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::volumioPushState May 25 03:00:44 volumio volumio[23847]: info: [squeezelite_mc] Server discovery started May 25 03:00:44 volumio volumio[23847]: info: [squeezelite_mc] Player finder started May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:44 volumio volumio[23847]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:00:44 volumio volumio[23847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:44 volumio volumio[23847]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:00:44 volumio volumio[23847]: at doSend (dgram.js:692:16) May 25 03:00:44 volumio volumio[23847]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:00:44 volumio volumio[23847]: at afterDns (dgram.js:638:5) May 25 03:00:44 volumio volumio[23847]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:00:44 volumio volumio[23847]: errno: -101, May 25 03:00:44 volumio volumio[23847]: code: 'ENETUNREACH', May 25 03:00:44 volumio volumio[23847]: syscall: 'send', May 25 03:00:44 volumio volumio[23847]: address: '255.255.255.255', May 25 03:00:44 volumio volumio[23847]: port: 3483 May 25 03:00:44 volumio volumio[23847]: } May 25 03:00:44 volumio volumio[23847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:44 volumio mpd[23993]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:00:44 volumio mpd[23993]: output: No 'audio_output' defined in config file May 25 03:00:44 volumio mpd[23993]: output: Successfully detected a sndio audio device May 25 03:00:44 volumio mpd[23993]: zeroconf: No global port, disabling zeroconf May 25 03:00:44 volumio systemd[1]: Started Music Player Daemon. May 25 03:00:44 volumio sudo[23945]: pam_unix(sudo:session): session closed for user root May 25 03:00:44 volumio sudo[23955]: pam_unix(sudo:session): session closed for user root May 25 03:00:44 volumio sudo[24030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 02:59 May 25 03:00:44 volumio sudo[24030]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:44 volumio sudo[24030]: pam_unix(sudo:session): session closed for user root May 25 03:00:44 volumio volumio-remote-updater[605]: [2025-05-25 03:00:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 25 03:00:44 volumio volumio-remote-updater[605]: [2025-05-25 03:00:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 25 03:00:44 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:00:44 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:00:45 volumio systemd[1]: Started dynamicswap service. May 25 03:00:45 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:00:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 25 03:00:45 volumio systemd[1]: Started dynamicswap service. May 25 03:00:45 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:00:45 volumio systemd[1]: Started Volumio Backend Module. May 25 03:00:45 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:00:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:00:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:00:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:00:46 volumio volumio[24044]: info: ------------------------------------------- May 25 03:00:46 volumio volumio[24044]: info: ----- Volumio3 ---- May 25 03:00:46 volumio volumio[24044]: info: ------------------------------------------- May 25 03:00:46 volumio volumio[24044]: info: ----- System startup ---- May 25 03:00:46 volumio volumio[24044]: info: ------------------------------------------- May 25 03:00:46 volumio volumio[24044]: info: MYVOLUMIO Environment detected May 25 03:00:46 volumio volumio[24044]: info: Plugin folders cleanup May 25 03:00:46 volumio volumio[24044]: info: Scanning into folder /volumio/app/plugins/ May 25 03:00:46 volumio volumio[24044]: info: Scanning category audio_interface May 25 03:00:46 volumio volumio[24044]: info: Scanning category miscellanea May 25 03:00:46 volumio volumio[24044]: info: Scanning category music_service May 25 03:00:46 volumio volumio[24044]: info: Scanning category plugins.json May 25 03:00:46 volumio volumio[24044]: info: Scanning category system_controller May 25 03:00:46 volumio volumio[24044]: info: Scanning category user_interface May 25 03:00:46 volumio volumio[24044]: info: Scanning into folder /data/plugins/ May 25 03:00:46 volumio volumio[24044]: info: Scanning category music_service May 25 03:00:46 volumio volumio[24044]: info: Scanning category user_interface May 25 03:00:46 volumio volumio[24044]: info: Plugin folders cleanup completed May 25 03:00:46 volumio volumio[24044]: info: ------------------------------------------- May 25 03:00:46 volumio volumio[24044]: info: ----- Core plugins startup ---- May 25 03:00:46 volumio volumio[24044]: info: ------------------------------------------- May 25 03:00:46 volumio volumio[24044]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:00:46 volumio volumio[24044]: info: Adding plugin upnp to MyMusic Plugins May 25 03:00:46 volumio volumio[24044]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:00:46 volumio volumio[24044]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:00:46 volumio volumio[24044]: info: Loading plugins from folder /data/plugins/ May 25 03:00:46 volumio volumio[24044]: info: Loading plugin "system"... May 25 03:00:46 volumio volumio[24044]: info: Loading plugin "appearance"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "network"... May 25 03:00:47 volumio volumio[24044]: info: Refreshing Cached IP Addresses May 25 03:00:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:00:47 volumio sudo[24071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:00:47 volumio sudo[24071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:47 volumio sudo[24071]: pam_unix(sudo:session): session closed for user root May 25 03:00:47 volumio sudo[24073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:00:47 volumio sudo[24073]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:47 volumio sudo[24073]: pam_unix(sudo:session): session closed for user root May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "services"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "alsa_controller"... May 25 03:00:47 volumio sudo[24082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:00:47 volumio sudo[24082]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:47 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "wizard"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "networkfs"... May 25 03:00:47 volumio volumio[24044]: info: Starting Udev Watcher for removable devices May 25 03:00:47 volumio volumio[24044]: info: Ignoring mount for partition: boot May 25 03:00:47 volumio volumio[24044]: info: Ignoring mount for partition: volumio May 25 03:00:47 volumio volumio[24044]: info: Ignoring mount for partition: volumio_data May 25 03:00:47 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "volumio_command_line_client"... May 25 03:00:47 volumio volumio[24044]: info: Plugin upnp is not enabled May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "my_music"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "mpd"... May 25 03:00:47 volumio volumio[24044]: info: Plugin upnp_browser is not enabled May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "alarm-clock"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "airplay_emulation"... May 25 03:00:47 volumio volumio[24044]: info: Starting Shairport Sync May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "last_100"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "webradio"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "i2s_dacs"... May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "volumiodiscovery"... May 25 03:00:47 volumio volumio[24044]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:47 volumio volumio[24044]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:47 volumio volumio[24044]: *** WARNING *** For more information see May 25 03:00:47 volumio node[24044]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:47 volumio volumio[24044]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:47 volumio volumio[24044]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:47 volumio volumio[24044]: *** WARNING *** For more information see May 25 03:00:47 volumio node[24044]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:47 volumio node[24044]: *** WARNING *** For more information see May 25 03:00:47 volumio node[24044]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:47 volumio node[24044]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:47 volumio node[24044]: *** WARNING *** For more information see May 25 03:00:47 volumio volumio[24044]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:00:47 volumio volumio[24044]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:00:47 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:47 volumio volumio[24044]: info: Loading plugin "squeezelite_mc"... May 25 03:00:48 volumio volumio[24044]: info: Loading plugin "outputs"... May 25 03:00:48 volumio volumio[24044]: info: Loading plugin "albumart"... May 25 03:00:48 volumio volumio[24044]: info: Plugin example_plugin is not enabled May 25 03:00:48 volumio volumio[24044]: info: Loading plugin "inputs"... May 25 03:00:48 volumio volumio[24044]: info: Loading plugin "updater_comm"... May 25 03:00:48 volumio volumio[24044]: info: Plugin mpdemulation is not enabled May 25 03:00:48 volumio volumio[24044]: info: Loading plugin "rest_api"... May 25 03:00:48 volumio volumio[24044]: info: Loading plugin "websocket"... May 25 03:00:48 volumio volumio[24044]: info: Starting Socket.io Server version 2.3.0 May 25 03:00:48 volumio volumio[24044]: info: Loading plugin "Systeminfo"... May 25 03:00:48 volumio volumio[24044]: Forking 3 albumart workers May 25 03:00:48 volumio volumio[24044]: info: Loading i18n strings for locale en May 25 03:00:48 volumio volumio[24044]: Updating browse sources language May 25 03:00:48 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::initPlayerControls May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:00:49 volumio volumio[24044]: Express server listening on port 3000 May 25 03:00:49 volumio volumio[24044]: [Metrics] WebUI: 2s 987.25ms May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::resetVolumioState May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::getcurrentVolume May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:49 volumio volumio[24044]: info: Volumio Network Manager: Network status updated: 0 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: Reloading queue from file May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::setRepeat null single undefined May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::pushState May 25 03:00:49 volumio volumio[24044]: info: CorePlayQueue::getTrack 0 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioPushState May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::setRandom null May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::pushState May 25 03:00:49 volumio volumio[24044]: info: CorePlayQueue::getTrack 0 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioPushState May 25 03:00:49 volumio volumio[24044]: info: Setting Device type: Raspberry PI May 25 03:00:49 volumio volumio[24044]: info: Completed loading Core Plugins May 25 03:00:49 volumio volumio[24044]: info: Preparing to generate the ALSA configuration file May 25 03:00:49 volumio volumio[24044]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::pushState May 25 03:00:49 volumio volumio[24044]: info: CorePlayQueue::getTrack 0 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioPushState May 25 03:00:49 volumio volumio[24044]: info: CoreStateMachine::updateTrackBlock May 25 03:00:49 volumio volumio[24044]: info: CorePlayQueue::getTrackBlock May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:49 volumio volumio[24044]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:00:49 volumio volumio[24044]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:00:49 volumio volumio[24044]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:00:49 volumio volumio[24044]: info: Asound.conf file unchanged, so no further update is needed May 25 03:00:49 volumio volumio[24044]: info: Output device has changed, restarting MPD May 25 03:00:49 volumio volumio[24044]: Starting albumart workers May 25 03:00:49 volumio sudo[24144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:00:49 volumio sudo[24144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:49 volumio sudo[24144]: pam_unix(sudo:session): session closed for user root May 25 03:00:49 volumio volumio[24044]: info: Output device has changed, restarting Shairport Sync May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:49 volumio sudo[24147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:00:49 volumio sudo[24147]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:49 volumio volumio[24044]: Starting albumart workers May 25 03:00:49 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:00:49 volumio volumio[24044]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:49 volumio volumio[24044]: info: ___________ START PLUGINS ___________ May 25 03:00:49 volumio systemd[1]: mpd.service: Succeeded. May 25 03:00:49 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:00:49 volumio volumio[24044]: info: ControllerMpd::onStart: Initializing MPD May 25 03:00:49 volumio volumio[24044]: info: Creating MPD Configuration file May 25 03:00:49 volumio sudo[24154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:00:49 volumio sudo[24154]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:49 volumio systemd[1]: Starting Music Player Daemon... May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:49 volumio sudo[24154]: pam_unix(sudo:session): session closed for user root May 25 03:00:49 volumio sudo[24158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:00:49 volumio sudo[24158]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:49 volumio volumio[24044]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:49 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 25 03:00:49 volumio systemd[1]: mpd.service: Succeeded. May 25 03:00:49 volumio volumio[24044]: Starting albumart workers May 25 03:00:49 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:00:49 volumio systemd[1]: Starting Music Player Daemon... May 25 03:00:49 volumio volumio[24044]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:00:49 volumio volumio[24044]: info: [1748113249565] CoreMusicLibrary::Adding element Last_100 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:00:49 volumio volumio[24044]: info: [1748113249573] CoreMusicLibrary::Adding element Webradio May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:49 volumio sudo[24163]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:00:49 volumio sudo[24163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:49 volumio volumio[24044]: info: Initializing BBC Radios May 25 03:00:49 volumio sudo[24163]: pam_unix(sudo:session): session closed for user root May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:49 volumio volumio[24044]: info: [squeezelite_mc] Starting proxy server... May 25 03:00:49 volumio volumio[24044]: info: Volumio Calling Home May 25 03:00:49 volumio volumio[24044]: info: [squeezelite_mc] Proxy server started on port 35967 May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:49 volumio volumio[24044]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:00:49 volumio volumio[24044]: info: Discovery: Found device VOLUMIO May 25 03:00:49 volumio volumio[24044]: info: CoreCommandRouter::volumioGetState May 25 03:00:49 volumio volumio[24044]: info: CorePlayQueue::getTrack 0 May 25 03:00:49 volumio volumio-remote-updater[605]: [2025-05-25 03:00:49] [connect] Successful connection May 25 03:00:49 volumio volumio[24044]: info: MPD Permissions set May 25 03:00:49 volumio volumio[24044]: info: MPD Permissions set May 25 03:00:50 volumio volumio[24044]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 25 03:00:50 volumio volumio-remote-updater[605]: [2025-05-25 03:00:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748113249 101 May 25 03:00:50 volumio volumio[24044]: 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 May 25 03:00:50 volumio volumio[24044]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:50 volumio volumio[24044]: info: CoreStateMachine::pushState May 25 03:00:50 volumio volumio[24044]: info: CorePlayQueue::getTrack 0 May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::volumioPushState May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:50 volumio volumio[24044]: info: CoreStateMachine::pushState May 25 03:00:50 volumio volumio[24044]: info: CorePlayQueue::getTrack 0 May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::volumioPushState May 25 03:00:50 volumio volumio[24044]: info: [squeezelite_mc] Server discovery started May 25 03:00:50 volumio volumio[24044]: info: [squeezelite_mc] Player finder started May 25 03:00:50 volumio volumio[24044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:50 volumio volumio[24044]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:00:50 volumio volumio[24044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:50 volumio volumio[24044]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:00:50 volumio volumio[24044]: at doSend (dgram.js:692:16) May 25 03:00:50 volumio volumio[24044]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:00:50 volumio volumio[24044]: at afterDns (dgram.js:638:5) May 25 03:00:50 volumio volumio[24044]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:00:50 volumio volumio[24044]: errno: -101, May 25 03:00:50 volumio volumio[24044]: code: 'ENETUNREACH', May 25 03:00:50 volumio volumio[24044]: syscall: 'send', May 25 03:00:50 volumio volumio[24044]: address: '255.255.255.255', May 25 03:00:50 volumio volumio[24044]: port: 3483 May 25 03:00:50 volumio volumio[24044]: } May 25 03:00:50 volumio volumio[24044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:50 volumio sudo[24082]: pam_unix(sudo:session): session closed for user root May 25 03:00:50 volumio mpd[24167]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:00:50 volumio mpd[24167]: output: No 'audio_output' defined in config file May 25 03:00:50 volumio mpd[24167]: output: Successfully detected a sndio audio device May 25 03:00:50 volumio mpd[24167]: zeroconf: No global port, disabling zeroconf May 25 03:00:50 volumio systemd[1]: Started Music Player Daemon. May 25 03:00:50 volumio sudo[24147]: pam_unix(sudo:session): session closed for user root May 25 03:00:50 volumio sudo[24158]: pam_unix(sudo:session): session closed for user root May 25 03:00:50 volumio sudo[24230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 02:59 May 25 03:00:50 volumio sudo[24230]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:50 volumio sudo[24230]: pam_unix(sudo:session): session closed for user root May 25 03:00:50 volumio volumio-remote-updater[605]: [2025-05-25 03:00:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 25 03:00:50 volumio volumio-remote-updater[605]: [2025-05-25 03:00:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 25 03:00:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:00:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:00:50 volumio systemd[1]: Started dynamicswap service. May 25 03:00:50 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:50 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:00:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. May 25 03:00:50 volumio systemd[1]: Started dynamicswap service. May 25 03:00:50 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:00:50 volumio systemd[1]: Started Volumio Backend Module. May 25 03:00:50 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:51 volumio volumio[24244]: info: ------------------------------------------- May 25 03:00:51 volumio volumio[24244]: info: ----- Volumio3 ---- May 25 03:00:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:00:51 volumio volumio[24244]: info: ------------------------------------------- May 25 03:00:51 volumio volumio[24244]: info: ----- System startup ---- May 25 03:00:51 volumio volumio[24244]: info: ------------------------------------------- May 25 03:00:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:00:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:00:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:00:52 volumio volumio[24244]: info: MYVOLUMIO Environment detected May 25 03:00:52 volumio volumio[24244]: info: Plugin folders cleanup May 25 03:00:52 volumio volumio[24244]: info: Scanning into folder /volumio/app/plugins/ May 25 03:00:52 volumio volumio[24244]: info: Scanning category audio_interface May 25 03:00:52 volumio volumio[24244]: info: Scanning category miscellanea May 25 03:00:52 volumio volumio[24244]: info: Scanning category music_service May 25 03:00:52 volumio volumio[24244]: info: Scanning category plugins.json May 25 03:00:52 volumio volumio[24244]: info: Scanning category system_controller May 25 03:00:52 volumio volumio[24244]: info: Scanning category user_interface May 25 03:00:52 volumio volumio[24244]: info: Scanning into folder /data/plugins/ May 25 03:00:52 volumio volumio[24244]: info: Scanning category music_service May 25 03:00:52 volumio volumio[24244]: info: Scanning category user_interface May 25 03:00:52 volumio volumio[24244]: info: Plugin folders cleanup completed May 25 03:00:52 volumio volumio[24244]: info: ------------------------------------------- May 25 03:00:52 volumio volumio[24244]: info: ----- Core plugins startup ---- May 25 03:00:52 volumio volumio[24244]: info: ------------------------------------------- May 25 03:00:52 volumio volumio[24244]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:00:52 volumio volumio[24244]: info: Adding plugin upnp to MyMusic Plugins May 25 03:00:52 volumio volumio[24244]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:00:52 volumio volumio[24244]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:00:52 volumio volumio[24244]: info: Loading plugins from folder /data/plugins/ May 25 03:00:52 volumio volumio[24244]: info: Loading plugin "system"... May 25 03:00:52 volumio volumio[24244]: info: Loading plugin "appearance"... May 25 03:00:52 volumio volumio[24244]: info: Loading plugin "network"... May 25 03:00:52 volumio volumio[24244]: info: Refreshing Cached IP Addresses May 25 03:00:52 volumio sudo[24270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:00:52 volumio sudo[24270]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:52 volumio sudo[24270]: pam_unix(sudo:session): session closed for user root May 25 03:00:52 volumio sudo[24272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:00:52 volumio sudo[24272]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:52 volumio sudo[24272]: pam_unix(sudo:session): session closed for user root May 25 03:00:52 volumio volumio[24244]: info: Loading plugin "services"... May 25 03:00:52 volumio volumio[24244]: info: Loading plugin "alsa_controller"... May 25 03:00:52 volumio sudo[24281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:00:52 volumio sudo[24281]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:52 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:52 volumio volumio[24244]: info: Loading plugin "wizard"... May 25 03:00:52 volumio volumio[24244]: info: Loading plugin "networkfs"... May 25 03:00:52 volumio volumio[24244]: info: Starting Udev Watcher for removable devices May 25 03:00:53 volumio volumio[24244]: info: Ignoring mount for partition: boot May 25 03:00:53 volumio volumio[24244]: info: Ignoring mount for partition: volumio May 25 03:00:53 volumio volumio[24244]: info: Ignoring mount for partition: volumio_data May 25 03:00:53 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "volumio_command_line_client"... May 25 03:00:53 volumio volumio[24244]: info: Plugin upnp is not enabled May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "my_music"... May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "mpd"... May 25 03:00:53 volumio volumio[24244]: info: Plugin upnp_browser is not enabled May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "alarm-clock"... May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "airplay_emulation"... May 25 03:00:53 volumio volumio[24244]: info: Starting Shairport Sync May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "last_100"... May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "webradio"... May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "i2s_dacs"... May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "volumiodiscovery"... May 25 03:00:53 volumio volumio[24244]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:53 volumio volumio[24244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:53 volumio volumio[24244]: *** WARNING *** For more information see May 25 03:00:53 volumio node[24244]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:53 volumio volumio[24244]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:53 volumio volumio[24244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:53 volumio volumio[24244]: *** WARNING *** For more information see May 25 03:00:53 volumio node[24244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:53 volumio node[24244]: *** WARNING *** For more information see May 25 03:00:53 volumio node[24244]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:53 volumio node[24244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:53 volumio node[24244]: *** WARNING *** For more information see May 25 03:00:53 volumio volumio[24244]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:00:53 volumio volumio[24244]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:00:53 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "squeezelite_mc"... May 25 03:00:53 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "outputs"... May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "albumart"... May 25 03:00:53 volumio volumio[24244]: info: Plugin example_plugin is not enabled May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "inputs"... May 25 03:00:53 volumio volumio[24244]: info: Loading plugin "updater_comm"... May 25 03:00:54 volumio volumio[24244]: info: Plugin mpdemulation is not enabled May 25 03:00:54 volumio volumio[24244]: info: Loading plugin "rest_api"... May 25 03:00:54 volumio volumio[24244]: info: Loading plugin "websocket"... May 25 03:00:54 volumio volumio[24244]: info: Starting Socket.io Server version 2.3.0 May 25 03:00:54 volumio volumio[24244]: info: Loading plugin "Systeminfo"... May 25 03:00:54 volumio volumio[24244]: Forking 3 albumart workers May 25 03:00:54 volumio wpa_supplicant[1054]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 May 25 03:00:54 volumio volumio[24244]: info: Loading i18n strings for locale en May 25 03:00:54 volumio volumio[24244]: Updating browse sources language May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::initPlayerControls May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:00:54 volumio volumio[24244]: Express server listening on port 3000 May 25 03:00:54 volumio volumio[24244]: [Metrics] WebUI: 3s 52.19ms May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::resetVolumioState May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::getcurrentVolume May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:54 volumio volumio[24244]: info: Volumio Network Manager: Network status updated: 0 May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: Reloading queue from file May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::setRepeat null single undefined May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::pushState May 25 03:00:54 volumio volumio[24244]: info: CorePlayQueue::getTrack 0 May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::volumioPushState May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::setRandom null May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::pushState May 25 03:00:54 volumio volumio[24244]: info: CorePlayQueue::getTrack 0 May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::volumioPushState May 25 03:00:54 volumio volumio[24244]: info: Setting Device type: Raspberry PI May 25 03:00:54 volumio volumio[24244]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::pushState May 25 03:00:54 volumio volumio[24244]: info: CorePlayQueue::getTrack 0 May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::volumioPushState May 25 03:00:54 volumio volumio[24244]: Starting albumart workers May 25 03:00:54 volumio volumio[24244]: info: CoreStateMachine::updateTrackBlock May 25 03:00:54 volumio volumio[24244]: info: CorePlayQueue::getTrackBlock May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:54 volumio volumio[24244]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:00:54 volumio volumio[24244]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:00:54 volumio volumio[24244]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:00:54 volumio volumio[24244]: info: Completed loading Core Plugins May 25 03:00:54 volumio volumio[24244]: info: Preparing to generate the ALSA configuration file May 25 03:00:54 volumio volumio[24244]: Starting albumart workers May 25 03:00:54 volumio volumio[24244]: Starting albumart workers May 25 03:00:54 volumio volumio[24244]: info: Asound.conf file unchanged, so no further update is needed May 25 03:00:54 volumio volumio[24244]: info: Output device has changed, restarting MPD May 25 03:00:54 volumio volumio[24244]: info: Output device has changed, restarting Shairport Sync May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:54 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:54 volumio sudo[24340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:00:54 volumio sudo[24340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:54 volumio sudo[24342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:00:54 volumio sudo[24342]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:54 volumio sudo[24340]: pam_unix(sudo:session): session closed for user root May 25 03:00:54 volumio volumio[24244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:54 volumio volumio[24244]: info: ___________ START PLUGINS ___________ May 25 03:00:54 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:00:54 volumio volumio[24244]: info: ControllerMpd::onStart: Initializing MPD May 25 03:00:54 volumio volumio[24244]: info: Creating MPD Configuration file May 25 03:00:55 volumio systemd[1]: mpd.service: Succeeded. May 25 03:00:55 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:55 volumio sudo[24349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:00:55 volumio sudo[24349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:55 volumio systemd[1]: Starting Music Player Daemon... May 25 03:00:55 volumio volumio[24244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:55 volumio sudo[24349]: pam_unix(sudo:session): session closed for user root May 25 03:00:55 volumio sudo[24352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:00:55 volumio sudo[24352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 25 03:00:55 volumio systemd[1]: mpd.service: Succeeded. May 25 03:00:55 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:00:55 volumio systemd[1]: Starting Music Player Daemon... May 25 03:00:55 volumio volumio[24244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:00:55 volumio volumio[24244]: info: [1748113255166] CoreMusicLibrary::Adding element Last_100 May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:00:55 volumio volumio[24244]: info: [1748113255169] CoreMusicLibrary::Adding element Webradio May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:55 volumio volumio[24244]: info: Initializing BBC Radios May 25 03:00:55 volumio sudo[24361]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:00:55 volumio sudo[24361]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:55 volumio sudo[24361]: pam_unix(sudo:session): session closed for user root May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: [squeezelite_mc] Starting proxy server... May 25 03:00:55 volumio volumio[24244]: info: Volumio Calling Home May 25 03:00:55 volumio volumio[24244]: info: [squeezelite_mc] Proxy server started on port 33163 May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:00:55 volumio volumio[24244]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:00:55 volumio volumio[24244]: info: Discovery: Found device VOLUMIO May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::volumioGetState May 25 03:00:55 volumio volumio[24244]: info: CorePlayQueue::getTrack 0 May 25 03:00:55 volumio volumio[24244]: info: MPD Permissions set May 25 03:00:55 volumio volumio[24244]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:55 volumio volumio[24244]: info: CoreStateMachine::pushState May 25 03:00:55 volumio volumio[24244]: info: CorePlayQueue::getTrack 0 May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::volumioPushState May 25 03:00:55 volumio volumio[24244]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: VolumeController:: Volume=79 Mute =false May 25 03:00:55 volumio volumio[24244]: info: CoreStateMachine::pushState May 25 03:00:55 volumio volumio[24244]: info: CorePlayQueue::getTrack 0 May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::volumioPushState May 25 03:00:55 volumio volumio[24244]: info: [squeezelite_mc] Server discovery started May 25 03:00:55 volumio volumio[24244]: info: [squeezelite_mc] Player finder started May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:00:55 volumio volumio[24244]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:00:55 volumio volumio[24244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:55 volumio volumio[24244]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:00:55 volumio volumio[24244]: at doSend (dgram.js:692:16) May 25 03:00:55 volumio volumio[24244]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:00:55 volumio volumio[24244]: at afterDns (dgram.js:638:5) May 25 03:00:55 volumio volumio[24244]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:00:55 volumio volumio[24244]: errno: -101, May 25 03:00:55 volumio volumio[24244]: code: 'ENETUNREACH', May 25 03:00:55 volumio volumio[24244]: syscall: 'send', May 25 03:00:55 volumio volumio[24244]: address: '255.255.255.255', May 25 03:00:55 volumio volumio[24244]: port: 3483 May 25 03:00:55 volumio volumio[24244]: } May 25 03:00:55 volumio volumio[24244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:00:55 volumio volumio-remote-updater[605]: [2025-05-25 03:00:55] [connect] Successful connection May 25 03:00:56 volumio sudo[24423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 02:59 May 25 03:00:56 volumio sudo[24423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:56 volumio mpd[24369]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:00:56 volumio mpd[24369]: output: No 'audio_output' defined in config file May 25 03:00:56 volumio mpd[24369]: output: Successfully detected a sndio audio device May 25 03:00:56 volumio mpd[24369]: zeroconf: No global port, disabling zeroconf May 25 03:00:56 volumio systemd[1]: Started Music Player Daemon. May 25 03:00:56 volumio sudo[24342]: pam_unix(sudo:session): session closed for user root May 25 03:00:56 volumio sudo[24352]: pam_unix(sudo:session): session closed for user root May 25 03:00:56 volumio sudo[24423]: pam_unix(sudo:session): session closed for user root May 25 03:00:56 volumio volumio-remote-updater[605]: [2025-05-25 03:00:56] [info] asio async_read_at_least error: system:104 (Connection reset by peer) May 25 03:00:56 volumio volumio-remote-updater[605]: [2025-05-25 03:00:56] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) May 25 03:00:56 volumio volumio-remote-updater[605]: [2025-05-25 03:00:56] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 25 03:00:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:00:56 volumio sudo[24281]: pam_unix(sudo:session): session closed for user root May 25 03:00:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:00:56 volumio systemd[1]: Started dynamicswap service. May 25 03:00:56 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:56 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:00:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. May 25 03:00:56 volumio systemd[1]: Started dynamicswap service. May 25 03:00:56 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:00:56 volumio systemd[1]: Started Volumio Backend Module. May 25 03:00:56 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:00:57 volumio volumio[24439]: info: ------------------------------------------- May 25 03:00:57 volumio volumio[24439]: info: ----- Volumio3 ---- May 25 03:00:57 volumio volumio[24439]: info: ------------------------------------------- May 25 03:00:57 volumio volumio[24439]: info: ----- System startup ---- May 25 03:00:57 volumio volumio[24439]: info: ------------------------------------------- May 25 03:00:57 volumio volumio[24439]: info: MYVOLUMIO Environment detected May 25 03:00:57 volumio volumio[24439]: info: Plugin folders cleanup May 25 03:00:57 volumio volumio[24439]: info: Scanning into folder /volumio/app/plugins/ May 25 03:00:57 volumio volumio[24439]: info: Scanning category audio_interface May 25 03:00:57 volumio volumio[24439]: info: Scanning category miscellanea May 25 03:00:57 volumio volumio[24439]: info: Scanning category music_service May 25 03:00:57 volumio volumio[24439]: info: Scanning category plugins.json May 25 03:00:57 volumio volumio[24439]: info: Scanning category system_controller May 25 03:00:57 volumio volumio[24439]: info: Scanning category user_interface May 25 03:00:57 volumio volumio[24439]: info: Scanning into folder /data/plugins/ May 25 03:00:57 volumio volumio[24439]: info: Scanning category music_service May 25 03:00:57 volumio volumio[24439]: info: Scanning category user_interface May 25 03:00:57 volumio volumio[24439]: info: Plugin folders cleanup completed May 25 03:00:57 volumio volumio[24439]: info: ------------------------------------------- May 25 03:00:57 volumio volumio[24439]: info: ----- Core plugins startup ---- May 25 03:00:57 volumio volumio[24439]: info: ------------------------------------------- May 25 03:00:57 volumio volumio[24439]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:00:57 volumio volumio[24439]: info: Adding plugin upnp to MyMusic Plugins May 25 03:00:57 volumio volumio[24439]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:00:57 volumio volumio[24439]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:00:57 volumio volumio[24439]: info: Loading plugins from folder /data/plugins/ May 25 03:00:57 volumio volumio[24439]: info: Loading plugin "system"... May 25 03:00:57 volumio volumio[24439]: info: Loading plugin "appearance"... May 25 03:00:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:00:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "network"... May 25 03:00:58 volumio volumio[24439]: info: Refreshing Cached IP Addresses May 25 03:00:58 volumio sudo[24465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:00:58 volumio sudo[24465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:58 volumio sudo[24465]: pam_unix(sudo:session): session closed for user root May 25 03:00:58 volumio sudo[24467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:00:58 volumio sudo[24467]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:58 volumio sudo[24467]: pam_unix(sudo:session): session closed for user root May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "services"... May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "alsa_controller"... May 25 03:00:58 volumio sudo[24476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:00:58 volumio sudo[24476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:00:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:00:58 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "wizard"... May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "networkfs"... May 25 03:00:58 volumio volumio[24439]: info: Starting Udev Watcher for removable devices May 25 03:00:58 volumio volumio[24439]: info: Ignoring mount for partition: boot May 25 03:00:58 volumio volumio[24439]: info: Ignoring mount for partition: volumio May 25 03:00:58 volumio volumio[24439]: info: Ignoring mount for partition: volumio_data May 25 03:00:58 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "volumio_command_line_client"... May 25 03:00:58 volumio volumio[24439]: info: Plugin upnp is not enabled May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "my_music"... May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "mpd"... May 25 03:00:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:00:58 volumio volumio[24439]: info: Plugin upnp_browser is not enabled May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "alarm-clock"... May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "airplay_emulation"... May 25 03:00:58 volumio volumio[24439]: info: Starting Shairport Sync May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "last_100"... May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "webradio"... May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "i2s_dacs"... May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "volumiodiscovery"... May 25 03:00:58 volumio volumio[24439]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:58 volumio volumio[24439]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:58 volumio volumio[24439]: *** WARNING *** For more information see May 25 03:00:58 volumio node[24439]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:00:58 volumio volumio[24439]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:58 volumio volumio[24439]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:58 volumio volumio[24439]: *** WARNING *** For more information see May 25 03:00:58 volumio node[24439]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:58 volumio node[24439]: *** WARNING *** For more information see May 25 03:00:58 volumio node[24439]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:00:58 volumio node[24439]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:00:58 volumio node[24439]: *** WARNING *** For more information see May 25 03:00:58 volumio volumio[24439]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:00:58 volumio volumio[24439]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:00:58 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:00:58 volumio volumio[24439]: info: Loading plugin "squeezelite_mc"... May 25 03:00:59 volumio volumio[24439]: info: Loading plugin "outputs"... May 25 03:00:59 volumio volumio[24439]: info: Loading plugin "albumart"... May 25 03:00:59 volumio volumio[24439]: info: Plugin example_plugin is not enabled May 25 03:00:59 volumio volumio[24439]: info: Loading plugin "inputs"... May 25 03:00:59 volumio volumio[24439]: info: Loading plugin "updater_comm"... May 25 03:00:59 volumio volumio[24439]: info: Plugin mpdemulation is not enabled May 25 03:00:59 volumio volumio[24439]: info: Loading plugin "rest_api"... May 25 03:00:59 volumio volumio[24439]: info: Loading plugin "websocket"... May 25 03:00:59 volumio volumio[24439]: info: Starting Socket.io Server version 2.3.0 May 25 03:00:59 volumio volumio[24439]: info: Loading plugin "Systeminfo"... May 25 03:00:59 volumio volumio[24439]: Forking 3 albumart workers May 25 03:00:59 volumio volumio[24439]: info: Loading i18n strings for locale en May 25 03:00:59 volumio volumio[24439]: Updating browse sources language May 25 03:00:59 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::initPlayerControls May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:00 volumio volumio[24439]: Express server listening on port 3000 May 25 03:01:00 volumio volumio[24439]: [Metrics] WebUI: 3s 67.32ms May 25 03:01:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::resetVolumioState May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::getcurrentVolume May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:00 volumio volumio[24439]: Starting albumart workers May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: Reloading queue from file May 25 03:01:00 volumio volumio[24439]: Starting albumart workers May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::setRepeat null single undefined May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::pushState May 25 03:01:00 volumio volumio[24439]: info: CorePlayQueue::getTrack 0 May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioPushState May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::setRandom null May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::pushState May 25 03:01:00 volumio volumio[24439]: info: CorePlayQueue::getTrack 0 May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioPushState May 25 03:01:00 volumio volumio[24439]: info: Setting Device type: Raspberry PI May 25 03:01:00 volumio volumio[24439]: info: Completed loading Core Plugins May 25 03:01:00 volumio volumio[24439]: info: Preparing to generate the ALSA configuration file May 25 03:01:00 volumio volumio[24439]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::pushState May 25 03:01:00 volumio volumio[24439]: info: CorePlayQueue::getTrack 0 May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioPushState May 25 03:01:00 volumio volumio[24439]: info: CoreStateMachine::updateTrackBlock May 25 03:01:00 volumio volumio[24439]: info: CorePlayQueue::getTrackBlock May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:00 volumio volumio[24439]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:01:00 volumio volumio[24439]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:01:00 volumio volumio[24439]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:01:00 volumio volumio[24439]: info: Asound.conf file unchanged, so no further update is needed May 25 03:01:00 volumio volumio[24439]: info: Output device has changed, restarting MPD May 25 03:01:00 volumio volumio[24439]: info: Output device has changed, restarting Shairport Sync May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:00 volumio volumio[24439]: info: ___________ START PLUGINS ___________ May 25 03:01:00 volumio volumio[24439]: info: ControllerMpd::onStart: Initializing MPD May 25 03:01:00 volumio volumio[24439]: info: Creating MPD Configuration file May 25 03:01:00 volumio volumio[24439]: Starting albumart workers May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:00 volumio volumio[24439]: info: [1748113260593] CoreMusicLibrary::Adding element Last_100 May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:00 volumio volumio[24439]: info: [1748113260596] CoreMusicLibrary::Adding element Webradio May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:00 volumio volumio[24439]: info: Initializing BBC Radios May 25 03:01:00 volumio sudo[24571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:00 volumio sudo[24573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:00 volumio sudo[24578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:00 volumio sudo[24578]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:00 volumio sudo[24573]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:00 volumio sudo[24571]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:00 volumio sudo[24580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:00 volumio sudo[24580]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:00 volumio sudo[24578]: pam_unix(sudo:session): session closed for user root May 25 03:01:00 volumio sudo[24571]: pam_unix(sudo:session): session closed for user root May 25 03:01:00 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:00 volumio volumio[24439]: info: [squeezelite_mc] Starting proxy server... May 25 03:01:00 volumio systemd[1]: mpd.service: Succeeded. May 25 03:01:00 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:01:00 volumio volumio[24439]: info: Volumio Calling Home May 25 03:01:00 volumio systemd[1]: Starting Music Player Daemon... May 25 03:01:00 volumio volumio[24439]: info: [squeezelite_mc] Proxy server started on port 39229 May 25 03:01:00 volumio sudo[24610]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:01:00 volumio sudo[24610]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:00 volumio sudo[24610]: pam_unix(sudo:session): session closed for user root May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:00 volumio volumio[24439]: info: Volumio Network Manager: Network status updated: 0 May 25 03:01:00 volumio volumio[24439]: info: MPD Permissions set May 25 03:01:00 volumio volumio[24439]: info: MPD Permissions set May 25 03:01:00 volumio volumio[24439]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:01:00 volumio volumio[24439]: info: Discovery: Found device VOLUMIO May 25 03:01:00 volumio volumio[24439]: info: CoreCommandRouter::volumioGetState May 25 03:01:00 volumio volumio[24439]: info: CorePlayQueue::getTrack 0 May 25 03:01:01 volumio volumio[24439]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 25 03:01:01 volumio volumio[24439]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:01 volumio volumio[24439]: info: CoreStateMachine::pushState May 25 03:01:01 volumio volumio[24439]: info: CorePlayQueue::getTrack 0 May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::volumioPushState May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:01 volumio volumio[24439]: info: CoreStateMachine::pushState May 25 03:01:01 volumio volumio[24439]: info: CorePlayQueue::getTrack 0 May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::volumioPushState May 25 03:01:01 volumio volumio[24439]: info: [squeezelite_mc] Server discovery started May 25 03:01:01 volumio volumio[24439]: info: [squeezelite_mc] Player finder started May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:01 volumio volumio[24439]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:01:01 volumio volumio[24439]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:01 volumio volumio[24439]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:01:01 volumio volumio[24439]: at doSend (dgram.js:692:16) May 25 03:01:01 volumio volumio[24439]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:01:01 volumio volumio[24439]: at afterDns (dgram.js:638:5) May 25 03:01:01 volumio volumio[24439]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:01:01 volumio volumio[24439]: errno: -101, May 25 03:01:01 volumio volumio[24439]: code: 'ENETUNREACH', May 25 03:01:01 volumio volumio[24439]: syscall: 'send', May 25 03:01:01 volumio volumio[24439]: address: '255.255.255.255', May 25 03:01:01 volumio volumio[24439]: port: 3483 May 25 03:01:01 volumio volumio[24439]: } May 25 03:01:01 volumio volumio[24439]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:01 volumio sudo[24654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 03:00 May 25 03:01:01 volumio sudo[24654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:01 volumio sudo[24654]: pam_unix(sudo:session): session closed for user root May 25 03:01:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:01:01 volumio sudo[24580]: pam_unix(sudo:session): session closed for user root May 25 03:01:01 volumio sudo[24476]: pam_unix(sudo:session): session closed for user root May 25 03:01:01 volumio sudo[24573]: pam_unix(sudo:session): session closed for user root May 25 03:01:01 volumio mpd[24623]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:01:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:01:01 volumio mpd[24623]: output: No 'audio_output' defined in config file May 25 03:01:01 volumio mpd[24623]: output: Successfully detected a sndio audio device May 25 03:01:01 volumio mpd[24623]: zeroconf: No global port, disabling zeroconf May 25 03:01:01 volumio systemd[1]: Started dynamicswap service. May 25 03:01:01 volumio systemd[1]: Started Music Player Daemon. May 25 03:01:01 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:01:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. May 25 03:01:01 volumio systemd[1]: Started dynamicswap service. May 25 03:01:01 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:01:01 volumio systemd[1]: Started Volumio Backend Module. May 25 03:01:01 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:02 volumio volumio[24670]: info: ------------------------------------------- May 25 03:01:02 volumio volumio[24670]: info: ----- Volumio3 ---- May 25 03:01:02 volumio volumio[24670]: info: ------------------------------------------- May 25 03:01:02 volumio volumio[24670]: info: ----- System startup ---- May 25 03:01:02 volumio volumio[24670]: info: ------------------------------------------- May 25 03:01:03 volumio volumio[24670]: info: MYVOLUMIO Environment detected May 25 03:01:03 volumio volumio[24670]: info: Plugin folders cleanup May 25 03:01:03 volumio volumio[24670]: info: Scanning into folder /volumio/app/plugins/ May 25 03:01:03 volumio volumio[24670]: info: Scanning category audio_interface May 25 03:01:03 volumio volumio[24670]: info: Scanning category miscellanea May 25 03:01:03 volumio volumio[24670]: info: Scanning category music_service May 25 03:01:03 volumio volumio[24670]: info: Scanning category plugins.json May 25 03:01:03 volumio volumio[24670]: info: Scanning category system_controller May 25 03:01:03 volumio volumio[24670]: info: Scanning category user_interface May 25 03:01:03 volumio volumio[24670]: info: Scanning into folder /data/plugins/ May 25 03:01:03 volumio volumio[24670]: info: Scanning category music_service May 25 03:01:03 volumio volumio[24670]: info: Scanning category user_interface May 25 03:01:03 volumio volumio[24670]: info: Plugin folders cleanup completed May 25 03:01:03 volumio volumio[24670]: info: ------------------------------------------- May 25 03:01:03 volumio volumio[24670]: info: ----- Core plugins startup ---- May 25 03:01:03 volumio volumio[24670]: info: ------------------------------------------- May 25 03:01:03 volumio volumio[24670]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:01:03 volumio volumio[24670]: info: Adding plugin upnp to MyMusic Plugins May 25 03:01:03 volumio volumio[24670]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:01:03 volumio volumio[24670]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:01:03 volumio volumio[24670]: info: Loading plugins from folder /data/plugins/ May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "system"... May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "appearance"... May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "network"... May 25 03:01:03 volumio volumio[24670]: info: Refreshing Cached IP Addresses May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "services"... May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "alsa_controller"... May 25 03:01:03 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "wizard"... May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "networkfs"... May 25 03:01:03 volumio volumio[24670]: info: Starting Udev Watcher for removable devices May 25 03:01:03 volumio volumio[24670]: info: Ignoring mount for partition: boot May 25 03:01:03 volumio volumio[24670]: info: Ignoring mount for partition: volumio May 25 03:01:03 volumio volumio[24670]: info: Ignoring mount for partition: volumio_data May 25 03:01:03 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "volumio_command_line_client"... May 25 03:01:03 volumio volumio[24670]: info: Plugin upnp is not enabled May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "my_music"... May 25 03:01:03 volumio volumio[24670]: info: Loading plugin "mpd"... May 25 03:01:04 volumio volumio[24670]: info: Plugin upnp_browser is not enabled May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "alarm-clock"... May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "airplay_emulation"... May 25 03:01:04 volumio volumio[24670]: info: Starting Shairport Sync May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "last_100"... May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "webradio"... May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "i2s_dacs"... May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "volumiodiscovery"... May 25 03:01:04 volumio volumio[24670]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:04 volumio volumio[24670]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:04 volumio volumio[24670]: *** WARNING *** For more information see May 25 03:01:04 volumio node[24670]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:04 volumio volumio[24670]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:04 volumio volumio[24670]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:04 volumio volumio[24670]: *** WARNING *** For more information see May 25 03:01:04 volumio node[24670]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:04 volumio node[24670]: *** WARNING *** For more information see May 25 03:01:04 volumio node[24670]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:04 volumio node[24670]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:04 volumio node[24670]: *** WARNING *** For more information see May 25 03:01:04 volumio volumio[24670]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:01:04 volumio volumio[24670]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:01:04 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "squeezelite_mc"... May 25 03:01:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:01:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:01:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "outputs"... May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "albumart"... May 25 03:01:04 volumio volumio[24670]: info: Plugin example_plugin is not enabled May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "inputs"... May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "updater_comm"... May 25 03:01:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:01:04 volumio volumio[24670]: info: Plugin mpdemulation is not enabled May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "rest_api"... May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "websocket"... May 25 03:01:04 volumio volumio[24670]: info: Starting Socket.io Server version 2.3.0 May 25 03:01:04 volumio volumio[24670]: info: Loading plugin "Systeminfo"... May 25 03:01:05 volumio volumio[24670]: Forking 3 albumart workers May 25 03:01:05 volumio volumio[24670]: info: Loading i18n strings for locale en May 25 03:01:05 volumio volumio[24670]: Updating browse sources language May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::initPlayerControls May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:05 volumio volumio[24670]: Express server listening on port 3000 May 25 03:01:05 volumio volumio[24670]: [Metrics] WebUI: 2s 996.41ms May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::resetVolumioState May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::getcurrentVolume May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:05 volumio volumio[24670]: info: Reloading queue from file May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::setRepeat null single undefined May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::pushState May 25 03:01:05 volumio volumio[24670]: info: CorePlayQueue::getTrack 0 May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::volumioPushState May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::setRandom null May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::pushState May 25 03:01:05 volumio volumio[24670]: info: CorePlayQueue::getTrack 0 May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::volumioPushState May 25 03:01:05 volumio volumio[24670]: info: Setting Device type: Raspberry PI May 25 03:01:05 volumio volumio[24670]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:01:05 volumio volumio[24670]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:01:05 volumio volumio[24670]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:01:05 volumio volumio[24670]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:05 volumio volumio[24670]: Starting albumart workers May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::pushState May 25 03:01:05 volumio volumio[24670]: info: CorePlayQueue::getTrack 0 May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::volumioPushState May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::updateTrackBlock May 25 03:01:05 volumio volumio[24670]: info: CorePlayQueue::getTrackBlock May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:05 volumio volumio[24670]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:01:05 volumio volumio[24670]: info: Discovery: Found device VOLUMIO May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::volumioGetState May 25 03:01:05 volumio volumio[24670]: info: CorePlayQueue::getTrack 0 May 25 03:01:05 volumio volumio[24670]: Starting albumart workers May 25 03:01:05 volumio volumio[24670]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:05 volumio volumio[24670]: info: CoreStateMachine::pushState May 25 03:01:05 volumio volumio[24670]: info: CorePlayQueue::getTrack 0 May 25 03:01:05 volumio volumio[24670]: info: CoreCommandRouter::volumioPushState May 25 03:01:05 volumio volumio[24670]: Starting albumart workers May 25 03:01:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:01:06 volumio volumio-remote-updater[605]: [2025-05-25 03:01:06] [connect] Successful connection May 25 03:01:06 volumio sudo[24698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:01:06 volumio sudo[24696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:01:06 volumio sudo[24698]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:06 volumio sudo[24696]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:06 volumio sudo[24696]: pam_unix(sudo:session): session closed for user root May 25 03:01:06 volumio sudo[24698]: pam_unix(sudo:session): session closed for user root May 25 03:01:06 volumio sudo[24705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:01:06 volumio sudo[24705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:06 volumio volumio-remote-updater[605]: [2025-05-25 03:01:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748113263 101 May 25 03:01:06 volumio sudo[24705]: pam_unix(sudo:session): session closed for user root May 25 03:01:06 volumio volumio[24670]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 25 03:01:06 volumio volumio[24670]: info: Volumio Network Manager: Network status updated: 0 May 25 03:01:06 volumio volumio[24670]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 25 03:01:06 volumio volumio[24670]: wlan0 Interface doesn't support scanning : Device or resource busy May 25 03:01:06 volumio volumio[24670]: info: Cannot use regular scanning, forcing with ap-force May 25 03:01:07 volumio sudo[24791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 25 03:01:07 volumio sudo[24791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:07 volumio sudo[24791]: pam_unix(sudo:session): session closed for user root May 25 03:01:07 volumio volumio[24670]: command failed: Device or resource busy (-16) May 25 03:01:07 volumio volumio[24670]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 25 03:01:07 volumio volumio[24670]: command failed: Device or resource busy (-16) May 25 03:01:07 volumio volumio[24670]: info: Completed loading Core Plugins May 25 03:01:07 volumio volumio[24670]: info: Preparing to generate the ALSA configuration file May 25 03:01:07 volumio volumio[24670]: info: Asound.conf file unchanged, so no further update is needed May 25 03:01:07 volumio volumio[24670]: info: Output device has changed, restarting MPD May 25 03:01:07 volumio sudo[24794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:07 volumio sudo[24794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:07 volumio volumio[24670]: info: Output device has changed, restarting Shairport Sync May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:07 volumio sudo[24794]: pam_unix(sudo:session): session closed for user root May 25 03:01:07 volumio sudo[24796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:07 volumio sudo[24796]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:07 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:01:07 volumio volumio[24670]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:07 volumio volumio[24670]: info: ___________ START PLUGINS ___________ May 25 03:01:07 volumio volumio[24670]: info: ControllerMpd::onStart: Initializing MPD May 25 03:01:07 volumio volumio[24670]: info: Creating MPD Configuration file May 25 03:01:07 volumio systemd[1]: mpd.service: Succeeded. May 25 03:01:07 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:01:07 volumio sudo[24804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:07 volumio sudo[24804]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:07 volumio sudo[24804]: pam_unix(sudo:session): session closed for user root May 25 03:01:07 volumio sudo[24806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:07 volumio systemd[1]: Starting Music Player Daemon... May 25 03:01:07 volumio sudo[24806]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:07 volumio volumio[24670]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:07 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 25 03:01:07 volumio systemd[1]: mpd.service: Succeeded. May 25 03:01:07 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:01:07 volumio systemd[1]: Starting Music Player Daemon... May 25 03:01:07 volumio volumio[24670]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:07 volumio volumio[24670]: info: [1748113267212] CoreMusicLibrary::Adding element Last_100 May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:07 volumio volumio[24670]: info: [1748113267216] CoreMusicLibrary::Adding element Webradio May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:07 volumio volumio[24670]: info: Initializing BBC Radios May 25 03:01:07 volumio sudo[24813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:01:07 volumio sudo[24813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:07 volumio sudo[24813]: pam_unix(sudo:session): session closed for user root May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: [squeezelite_mc] Starting proxy server... May 25 03:01:07 volumio volumio[24670]: info: Volumio Calling Home May 25 03:01:07 volumio volumio[24670]: info: [squeezelite_mc] Proxy server started on port 42049 May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:07 volumio volumio[24670]: info: MPD Permissions set May 25 03:01:07 volumio volumio[24670]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:07 volumio volumio[24670]: info: CoreStateMachine::pushState May 25 03:01:07 volumio volumio[24670]: info: CorePlayQueue::getTrack 0 May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::volumioPushState May 25 03:01:07 volumio volumio[24670]: info: [squeezelite_mc] Server discovery started May 25 03:01:07 volumio volumio[24670]: info: [squeezelite_mc] Player finder started May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:07 volumio volumio[24670]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:01:07 volumio volumio[24670]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:07 volumio volumio[24670]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:01:07 volumio volumio[24670]: at doSend (dgram.js:692:16) May 25 03:01:07 volumio volumio[24670]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:01:07 volumio volumio[24670]: at afterDns (dgram.js:638:5) May 25 03:01:07 volumio volumio[24670]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:01:07 volumio volumio[24670]: errno: -101, May 25 03:01:07 volumio volumio[24670]: code: 'ENETUNREACH', May 25 03:01:07 volumio volumio[24670]: syscall: 'send', May 25 03:01:07 volumio volumio[24670]: address: '255.255.255.255', May 25 03:01:07 volumio volumio[24670]: port: 3483 May 25 03:01:07 volumio volumio[24670]: } May 25 03:01:07 volumio volumio[24670]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:08 volumio sudo[24853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 03:00 May 25 03:01:08 volumio sudo[24853]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:08 volumio mpd[24824]: May 25 03:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:01:08 volumio systemd[1]: Started Music Player Daemon. May 25 03:01:08 volumio sudo[24806]: pam_unix(sudo:session): session closed for user root May 25 03:01:08 volumio sudo[24796]: pam_unix(sudo:session): session closed for user root May 25 03:01:08 volumio sudo[24853]: pam_unix(sudo:session): session closed for user root May 25 03:01:08 volumio volumio-remote-updater[605]: [2025-05-25 03:01:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 25 03:01:08 volumio volumio-remote-updater[605]: [2025-05-25 03:01:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 25 03:01:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:01:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:01:08 volumio systemd[1]: Started dynamicswap service. May 25 03:01:08 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:01:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. May 25 03:01:08 volumio systemd[1]: Started dynamicswap service. May 25 03:01:08 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:01:08 volumio systemd[1]: Started Volumio Backend Module. May 25 03:01:08 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:09 volumio volumio[24869]: info: ------------------------------------------- May 25 03:01:09 volumio volumio[24869]: info: ----- Volumio3 ---- May 25 03:01:09 volumio volumio[24869]: info: ------------------------------------------- May 25 03:01:09 volumio volumio[24869]: info: ----- System startup ---- May 25 03:01:09 volumio volumio[24869]: info: ------------------------------------------- May 25 03:01:09 volumio volumio[24869]: info: MYVOLUMIO Environment detected May 25 03:01:09 volumio volumio[24869]: info: Plugin folders cleanup May 25 03:01:09 volumio volumio[24869]: info: Scanning into folder /volumio/app/plugins/ May 25 03:01:09 volumio volumio[24869]: info: Scanning category audio_interface May 25 03:01:09 volumio volumio[24869]: info: Scanning category miscellanea May 25 03:01:09 volumio volumio[24869]: info: Scanning category music_service May 25 03:01:09 volumio volumio[24869]: info: Scanning category plugins.json May 25 03:01:09 volumio volumio[24869]: info: Scanning category system_controller May 25 03:01:09 volumio volumio[24869]: info: Scanning category user_interface May 25 03:01:09 volumio volumio[24869]: info: Scanning into folder /data/plugins/ May 25 03:01:09 volumio volumio[24869]: info: Scanning category music_service May 25 03:01:09 volumio volumio[24869]: info: Scanning category user_interface May 25 03:01:09 volumio volumio[24869]: info: Plugin folders cleanup completed May 25 03:01:09 volumio volumio[24869]: info: ------------------------------------------- May 25 03:01:09 volumio volumio[24869]: info: ----- Core plugins startup ---- May 25 03:01:09 volumio volumio[24869]: info: ------------------------------------------- May 25 03:01:09 volumio volumio[24869]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:01:09 volumio volumio[24869]: info: Adding plugin upnp to MyMusic Plugins May 25 03:01:09 volumio volumio[24869]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:01:09 volumio volumio[24869]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:01:09 volumio volumio[24869]: info: Loading plugins from folder /data/plugins/ May 25 03:01:09 volumio volumio[24869]: info: Loading plugin "system"... May 25 03:01:09 volumio volumio[24869]: info: Loading plugin "appearance"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "network"... May 25 03:01:10 volumio volumio[24869]: info: Refreshing Cached IP Addresses May 25 03:01:10 volumio sudo[24898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:01:10 volumio sudo[24898]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:10 volumio sudo[24898]: pam_unix(sudo:session): session closed for user root May 25 03:01:10 volumio sudo[24900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:01:10 volumio sudo[24900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:10 volumio sudo[24900]: pam_unix(sudo:session): session closed for user root May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "services"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "alsa_controller"... May 25 03:01:10 volumio sudo[24913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:01:10 volumio sudo[24913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:10 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "wizard"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "networkfs"... May 25 03:01:10 volumio volumio[24869]: info: Starting Udev Watcher for removable devices May 25 03:01:10 volumio volumio[24869]: info: Ignoring mount for partition: boot May 25 03:01:10 volumio volumio[24869]: info: Ignoring mount for partition: volumio May 25 03:01:10 volumio volumio[24869]: info: Ignoring mount for partition: volumio_data May 25 03:01:10 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "volumio_command_line_client"... May 25 03:01:10 volumio volumio[24869]: info: Plugin upnp is not enabled May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "my_music"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "mpd"... May 25 03:01:10 volumio volumio[24869]: info: Plugin upnp_browser is not enabled May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "alarm-clock"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "airplay_emulation"... May 25 03:01:10 volumio volumio[24869]: info: Starting Shairport Sync May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "last_100"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "webradio"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "i2s_dacs"... May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "volumiodiscovery"... May 25 03:01:10 volumio volumio[24869]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:01:10 volumio node[24869]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:10 volumio volumio[24869]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:10 volumio volumio[24869]: *** WARNING *** For more information see May 25 03:01:10 volumio volumio[24869]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:10 volumio volumio[24869]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:10 volumio volumio[24869]: *** WARNING *** For more information see May 25 03:01:10 volumio node[24869]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:10 volumio node[24869]: *** WARNING *** For more information see May 25 03:01:10 volumio node[24869]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:10 volumio node[24869]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:10 volumio node[24869]: *** WARNING *** For more information see May 25 03:01:10 volumio volumio[24869]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:01:10 volumio volumio[24869]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:01:10 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:10 volumio volumio[24869]: info: Loading plugin "squeezelite_mc"... May 25 03:01:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:01:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:01:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:01:11 volumio volumio[24869]: info: Loading plugin "outputs"... May 25 03:01:11 volumio volumio[24869]: info: Loading plugin "albumart"... May 25 03:01:11 volumio volumio[24869]: info: Plugin example_plugin is not enabled May 25 03:01:11 volumio volumio[24869]: info: Loading plugin "inputs"... May 25 03:01:11 volumio volumio[24869]: info: Loading plugin "updater_comm"... May 25 03:01:11 volumio volumio[24869]: info: Plugin mpdemulation is not enabled May 25 03:01:11 volumio volumio[24869]: info: Loading plugin "rest_api"... May 25 03:01:11 volumio volumio[24869]: info: Loading plugin "websocket"... May 25 03:01:11 volumio volumio[24869]: info: Starting Socket.io Server version 2.3.0 May 25 03:01:11 volumio volumio[24869]: info: Loading plugin "Systeminfo"... May 25 03:01:11 volumio volumio[24869]: Forking 3 albumart workers May 25 03:01:11 volumio volumio[24869]: info: Loading i18n strings for locale en May 25 03:01:11 volumio volumio[24869]: Updating browse sources language May 25 03:01:11 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::initPlayerControls May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:12 volumio volumio[24869]: Express server listening on port 3000 May 25 03:01:12 volumio volumio[24869]: [Metrics] WebUI: 3s 123.83ms May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::resetVolumioState May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::getcurrentVolume May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:12 volumio volumio[24869]: info: Volumio Network Manager: Network status updated: 0 May 25 03:01:12 volumio volumio[24869]: Starting albumart workers May 25 03:01:12 volumio volumio[24869]: Starting albumart workers May 25 03:01:12 volumio volumio[24869]: Starting albumart workers May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: Reloading queue from file May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::setRepeat null single undefined May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::pushState May 25 03:01:12 volumio volumio[24869]: info: CorePlayQueue::getTrack 0 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioPushState May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::setRandom null May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::pushState May 25 03:01:12 volumio volumio[24869]: info: CorePlayQueue::getTrack 0 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioPushState May 25 03:01:12 volumio volumio[24869]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::pushState May 25 03:01:12 volumio volumio[24869]: info: CorePlayQueue::getTrack 0 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioPushState May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::updateTrackBlock May 25 03:01:12 volumio volumio[24869]: info: CorePlayQueue::getTrackBlock May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:12 volumio volumio[24869]: info: Setting Device type: Raspberry PI May 25 03:01:12 volumio volumio[24869]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:01:12 volumio volumio[24869]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:01:12 volumio volumio[24869]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:01:12 volumio volumio[24869]: info: Completed loading Core Plugins May 25 03:01:12 volumio volumio[24869]: info: Preparing to generate the ALSA configuration file May 25 03:01:12 volumio volumio[24869]: info: Asound.conf file unchanged, so no further update is needed May 25 03:01:12 volumio volumio[24869]: info: Output device has changed, restarting MPD May 25 03:01:12 volumio volumio[24869]: info: Output device has changed, restarting Shairport Sync May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:12 volumio volumio[24869]: info: ___________ START PLUGINS ___________ May 25 03:01:12 volumio volumio[24869]: info: ControllerMpd::onStart: Initializing MPD May 25 03:01:12 volumio volumio[24869]: info: Creating MPD Configuration file May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:12 volumio volumio[24869]: info: [1748113272702] CoreMusicLibrary::Adding element Last_100 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:12 volumio volumio[24869]: info: [1748113272705] CoreMusicLibrary::Adding element Webradio May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:12 volumio volumio[24869]: info: Initializing BBC Radios May 25 03:01:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: [squeezelite_mc] Starting proxy server... May 25 03:01:12 volumio volumio[24869]: info: Volumio Calling Home May 25 03:01:12 volumio volumio[24869]: info: [squeezelite_mc] Proxy server started on port 36995 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:12 volumio volumio[24869]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:01:12 volumio volumio[24869]: info: Discovery: Found device VOLUMIO May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioGetState May 25 03:01:12 volumio volumio[24869]: info: CorePlayQueue::getTrack 0 May 25 03:01:12 volumio volumio[24869]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::pushState May 25 03:01:12 volumio volumio[24869]: info: CorePlayQueue::getTrack 0 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioPushState May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:12 volumio volumio[24869]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:12 volumio volumio[24869]: info: CoreStateMachine::pushState May 25 03:01:12 volumio volumio[24869]: info: CorePlayQueue::getTrack 0 May 25 03:01:12 volumio volumio[24869]: info: CoreCommandRouter::volumioPushState May 25 03:01:13 volumio volumio-remote-updater[605]: [2025-05-25 03:01:13] [connect] Successful connection May 25 03:01:13 volumio sudo[24975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:13 volumio sudo[24968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:13 volumio volumio[24869]: info: [squeezelite_mc] Server discovery started May 25 03:01:13 volumio volumio[24869]: info: [squeezelite_mc] Player finder started May 25 03:01:13 volumio sudo[24970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:13 volumio sudo[24968]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:13 volumio sudo[24970]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:13 volumio volumio[24869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:13 volumio sudo[24982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:13 volumio sudo[24982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:13 volumio sudo[24975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:13 volumio sudo[24968]: pam_unix(sudo:session): session closed for user root May 25 03:01:13 volumio volumio[24869]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:01:13 volumio sudo[24975]: pam_unix(sudo:session): session closed for user root May 25 03:01:13 volumio volumio[24869]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:13 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:01:13 volumio volumio[24869]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:01:13 volumio volumio[24869]: at doSend (dgram.js:692:16) May 25 03:01:13 volumio volumio[24869]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:01:13 volumio volumio[24869]: at afterDns (dgram.js:638:5) May 25 03:01:13 volumio volumio[24869]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:01:13 volumio volumio[24869]: errno: -101, May 25 03:01:13 volumio volumio[24869]: code: 'ENETUNREACH', May 25 03:01:13 volumio volumio[24869]: syscall: 'send', May 25 03:01:13 volumio volumio[24869]: address: '255.255.255.255', May 25 03:01:13 volumio volumio[24869]: port: 3483 May 25 03:01:13 volumio volumio[24869]: } May 25 03:01:13 volumio volumio[24869]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:13 volumio systemd[1]: mpd.service: Succeeded. May 25 03:01:13 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:01:13 volumio systemd[1]: Starting Music Player Daemon... May 25 03:01:13 volumio sudo[25039]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:01:13 volumio sudo[25039]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:13 volumio sudo[25039]: pam_unix(sudo:session): session closed for user root May 25 03:01:13 volumio sudo[25049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 03:00 May 25 03:01:13 volumio sudo[25049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:13 volumio sudo[25049]: pam_unix(sudo:session): session closed for user root May 25 03:01:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:01:13 volumio volumio-remote-updater[605]: [2025-05-25 03:01:13] [info] asio async_read_at_least error: system:104 (Connection reset by peer) May 25 03:01:13 volumio volumio-remote-updater[605]: [2025-05-25 03:01:13] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) May 25 03:01:13 volumio volumio-remote-updater[605]: [2025-05-25 03:01:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 25 03:01:13 volumio sudo[24982]: pam_unix(sudo:session): session closed for user root May 25 03:01:13 volumio sudo[24913]: pam_unix(sudo:session): session closed for user root May 25 03:01:13 volumio sudo[24970]: pam_unix(sudo:session): session closed for user root May 25 03:01:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:01:13 volumio systemd[1]: Started dynamicswap service. May 25 03:01:13 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:01:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. May 25 03:01:14 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:01:14 volumio systemd[1]: Started Volumio Backend Module. May 25 03:01:14 volumio systemd[1]: Started dynamicswap service. May 25 03:01:14 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:14 volumio mpd[25047]: May 25 03:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:01:14 volumio systemd[1]: Started Music Player Daemon. May 25 03:01:15 volumio volumio[25062]: info: ------------------------------------------- May 25 03:01:15 volumio volumio[25062]: info: ----- Volumio3 ---- May 25 03:01:15 volumio volumio[25062]: info: ------------------------------------------- May 25 03:01:15 volumio volumio[25062]: info: ----- System startup ---- May 25 03:01:15 volumio volumio[25062]: info: ------------------------------------------- May 25 03:01:15 volumio volumio[25062]: info: MYVOLUMIO Environment detected May 25 03:01:15 volumio volumio[25062]: info: Plugin folders cleanup May 25 03:01:15 volumio volumio[25062]: info: Scanning into folder /volumio/app/plugins/ May 25 03:01:15 volumio volumio[25062]: info: Scanning category audio_interface May 25 03:01:15 volumio volumio[25062]: info: Scanning category miscellanea May 25 03:01:15 volumio volumio[25062]: info: Scanning category music_service May 25 03:01:15 volumio volumio[25062]: info: Scanning category plugins.json May 25 03:01:15 volumio volumio[25062]: info: Scanning category system_controller May 25 03:01:15 volumio volumio[25062]: info: Scanning category user_interface May 25 03:01:15 volumio volumio[25062]: info: Scanning into folder /data/plugins/ May 25 03:01:15 volumio volumio[25062]: info: Scanning category music_service May 25 03:01:15 volumio volumio[25062]: info: Scanning category user_interface May 25 03:01:15 volumio volumio[25062]: info: Plugin folders cleanup completed May 25 03:01:15 volumio volumio[25062]: info: ------------------------------------------- May 25 03:01:15 volumio volumio[25062]: info: ----- Core plugins startup ---- May 25 03:01:15 volumio volumio[25062]: info: ------------------------------------------- May 25 03:01:15 volumio volumio[25062]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:01:15 volumio volumio[25062]: info: Adding plugin upnp to MyMusic Plugins May 25 03:01:15 volumio volumio[25062]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:01:15 volumio volumio[25062]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:01:15 volumio volumio[25062]: info: Loading plugins from folder /data/plugins/ May 25 03:01:15 volumio volumio[25062]: info: Loading plugin "system"... May 25 03:01:15 volumio volumio[25062]: info: Loading plugin "appearance"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "network"... May 25 03:01:16 volumio volumio[25062]: info: Refreshing Cached IP Addresses May 25 03:01:16 volumio sudo[25091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:01:16 volumio sudo[25091]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:16 volumio sudo[25091]: pam_unix(sudo:session): session closed for user root May 25 03:01:16 volumio sudo[25093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:01:16 volumio sudo[25093]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:16 volumio sudo[25093]: pam_unix(sudo:session): session closed for user root May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "services"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "alsa_controller"... May 25 03:01:16 volumio sudo[25103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:01:16 volumio sudo[25103]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:16 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "wizard"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "networkfs"... May 25 03:01:16 volumio volumio[25062]: info: Starting Udev Watcher for removable devices May 25 03:01:16 volumio volumio[25062]: info: Ignoring mount for partition: boot May 25 03:01:16 volumio volumio[25062]: info: Ignoring mount for partition: volumio May 25 03:01:16 volumio volumio[25062]: info: Ignoring mount for partition: volumio_data May 25 03:01:16 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "volumio_command_line_client"... May 25 03:01:16 volumio volumio[25062]: info: Plugin upnp is not enabled May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "my_music"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "mpd"... May 25 03:01:16 volumio volumio[25062]: info: Plugin upnp_browser is not enabled May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "alarm-clock"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "airplay_emulation"... May 25 03:01:16 volumio volumio[25062]: info: Starting Shairport Sync May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "last_100"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "webradio"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "i2s_dacs"... May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "volumiodiscovery"... May 25 03:01:16 volumio volumio[25062]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:16 volumio volumio[25062]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:16 volumio volumio[25062]: *** WARNING *** For more information see May 25 03:01:16 volumio node[25062]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:16 volumio volumio[25062]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:16 volumio volumio[25062]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:16 volumio volumio[25062]: *** WARNING *** For more information see May 25 03:01:16 volumio node[25062]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:16 volumio node[25062]: *** WARNING *** For more information see May 25 03:01:16 volumio node[25062]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:16 volumio node[25062]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:16 volumio node[25062]: *** WARNING *** For more information see May 25 03:01:16 volumio volumio[25062]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:01:16 volumio volumio[25062]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:01:16 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:16 volumio volumio[25062]: info: Loading plugin "squeezelite_mc"... May 25 03:01:17 volumio volumio[25062]: info: Loading plugin "outputs"... May 25 03:01:17 volumio volumio[25062]: info: Loading plugin "albumart"... May 25 03:01:17 volumio volumio[25062]: info: Plugin example_plugin is not enabled May 25 03:01:17 volumio volumio[25062]: info: Loading plugin "inputs"... May 25 03:01:17 volumio volumio[25062]: info: Loading plugin "updater_comm"... May 25 03:01:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 25 03:01:17 volumio volumio[25062]: info: Plugin mpdemulation is not enabled May 25 03:01:17 volumio volumio[25062]: info: Loading plugin "rest_api"... May 25 03:01:17 volumio volumio[25062]: info: Loading plugin "websocket"... May 25 03:01:17 volumio volumio[25062]: info: Starting Socket.io Server version 2.3.0 May 25 03:01:17 volumio volumio[25062]: info: Loading plugin "Systeminfo"... May 25 03:01:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 25 03:01:17 volumio volumio[25062]: Forking 3 albumart workers May 25 03:01:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 25 03:01:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 25 03:01:17 volumio volumio[25062]: info: Loading i18n strings for locale en May 25 03:01:17 volumio volumio[25062]: Updating browse sources language May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::initPlayerControls May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:17 volumio volumio[25062]: Express server listening on port 3000 May 25 03:01:17 volumio volumio[25062]: [Metrics] WebUI: 3s 63.48ms May 25 03:01:17 volumio volumio[25062]: info: CoreStateMachine::resetVolumioState May 25 03:01:17 volumio volumio[25062]: info: CoreStateMachine::getcurrentVolume May 25 03:01:17 volumio volumio[25062]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:17 volumio volumio[25062]: Starting albumart workers May 25 03:01:18 volumio volumio[25062]: Starting albumart workers May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: Reloading queue from file May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::setRepeat null single undefined May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::pushState May 25 03:01:18 volumio volumio[25062]: info: CorePlayQueue::getTrack 0 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioPushState May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::setRandom null May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::pushState May 25 03:01:18 volumio volumio[25062]: info: CorePlayQueue::getTrack 0 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioPushState May 25 03:01:18 volumio volumio[25062]: info: Setting Device type: Raspberry PI May 25 03:01:18 volumio volumio[25062]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::pushState May 25 03:01:18 volumio volumio[25062]: info: CorePlayQueue::getTrack 0 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioPushState May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::updateTrackBlock May 25 03:01:18 volumio volumio[25062]: info: CorePlayQueue::getTrackBlock May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:18 volumio volumio[25062]: Starting albumart workers May 25 03:01:18 volumio volumio[25062]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:01:18 volumio volumio[25062]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:01:18 volumio volumio[25062]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:01:18 volumio volumio[25062]: info: Completed loading Core Plugins May 25 03:01:18 volumio volumio[25062]: info: Preparing to generate the ALSA configuration file May 25 03:01:18 volumio volumio[25062]: info: Asound.conf file unchanged, so no further update is needed May 25 03:01:18 volumio volumio[25062]: info: Output device has changed, restarting MPD May 25 03:01:18 volumio volumio[25062]: info: Output device has changed, restarting Shairport Sync May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:18 volumio volumio[25062]: info: ___________ START PLUGINS ___________ May 25 03:01:18 volumio volumio[25062]: info: ControllerMpd::onStart: Initializing MPD May 25 03:01:18 volumio volumio[25062]: info: Creating MPD Configuration file May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:18 volumio volumio[25062]: info: [1748113278350] CoreMusicLibrary::Adding element Last_100 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:18 volumio volumio[25062]: info: [1748113278355] CoreMusicLibrary::Adding element Webradio May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:18 volumio volumio[25062]: info: Initializing BBC Radios May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: [squeezelite_mc] Starting proxy server... May 25 03:01:18 volumio volumio[25062]: info: Volumio Calling Home May 25 03:01:18 volumio volumio[25062]: info: [squeezelite_mc] Proxy server started on port 37935 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:18 volumio volumio[25062]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:01:18 volumio volumio[25062]: info: Discovery: Found device VOLUMIO May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioGetState May 25 03:01:18 volumio volumio[25062]: info: CorePlayQueue::getTrack 0 May 25 03:01:18 volumio volumio[25062]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::pushState May 25 03:01:18 volumio volumio[25062]: info: CorePlayQueue::getTrack 0 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioPushState May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:18 volumio volumio[25062]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:18 volumio volumio[25062]: info: CoreStateMachine::pushState May 25 03:01:18 volumio volumio[25062]: info: CorePlayQueue::getTrack 0 May 25 03:01:18 volumio volumio[25062]: info: CoreCommandRouter::volumioPushState May 25 03:01:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 25 03:01:19 volumio wpa_supplicant[1054]: wlan0: Trying to associate with SSID 'botbot' May 25 03:01:19 volumio sudo[25160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:19 volumio sudo[25169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:19 volumio volumio[25062]: info: [squeezelite_mc] Server discovery started May 25 03:01:19 volumio volumio[25062]: info: [squeezelite_mc] Player finder started May 25 03:01:19 volumio sudo[25160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:19 volumio volumio[25062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:19 volumio sudo[25167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:19 volumio sudo[25167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:19 volumio sudo[25160]: pam_unix(sudo:session): session closed for user root May 25 03:01:19 volumio sudo[25162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:19 volumio sudo[25162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:19 volumio volumio[25062]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:01:19 volumio sudo[25169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:19 volumio sudo[25167]: pam_unix(sudo:session): session closed for user root May 25 03:01:19 volumio volumio[25062]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:19 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:01:19 volumio volumio[25062]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:01:19 volumio volumio[25062]: at doSend (dgram.js:692:16) May 25 03:01:19 volumio volumio[25062]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:01:19 volumio volumio[25062]: at afterDns (dgram.js:638:5) May 25 03:01:19 volumio volumio[25062]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:01:19 volumio volumio[25062]: errno: -101, May 25 03:01:19 volumio volumio[25062]: code: 'ENETUNREACH', May 25 03:01:19 volumio volumio[25062]: syscall: 'send', May 25 03:01:19 volumio volumio[25062]: address: '255.255.255.255', May 25 03:01:19 volumio volumio[25062]: port: 3483 May 25 03:01:19 volumio volumio[25062]: } May 25 03:01:19 volumio volumio[25062]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:19 volumio systemd[1]: mpd.service: Succeeded. May 25 03:01:19 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:01:19 volumio systemd[1]: Starting Music Player Daemon... May 25 03:01:19 volumio sudo[25103]: pam_unix(sudo:session): session closed for user root May 25 03:01:19 volumio sudo[25237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:01:19 volumio sudo[25237]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:19 volumio sudo[25237]: pam_unix(sudo:session): session closed for user root May 25 03:01:19 volumio dhcpcd[1085]: wlan0: carrier acquired May 25 03:01:19 volumio wpa_supplicant[1054]: wlan0: Associated with 7a:c1:c4:1a:ed:80 May 25 03:01:19 volumio wpa_supplicant[1054]: wlan0: CTRL-EVENT-CONNECTED - Connection to 7a:c1:c4:1a:ed:80 completed [id=0 id_str=] May 25 03:01:19 volumio wpa_supplicant[1054]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 25 03:01:19 volumio wpa_supplicant[1054]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AU May 25 03:01:19 volumio dhcpcd[1085]: wlan0: IAID 32:52:ab:68 May 25 03:01:19 volumio dhcpcd[1085]: wlan0: carrier lost May 25 03:01:19 volumio dhcpcd[1085]: wlan0: carrier acquired May 25 03:01:19 volumio dhcpcd[1085]: wlan0: IAID 32:52:ab:68 May 25 03:01:19 volumio sudo[25254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 03:00 May 25 03:01:19 volumio sudo[25254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:19 volumio sudo[25254]: pam_unix(sudo:session): session closed for user root May 25 03:01:20 volumio dhcpcd[1085]: wlan0: rebinding lease of 192.168.0.248 May 25 03:01:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 03:01:20 volumio sudo[25162]: pam_unix(sudo:session): session closed for user root May 25 03:01:20 volumio sudo[25169]: pam_unix(sudo:session): session closed for user root May 25 03:01:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 03:01:20 volumio dhcpcd[1085]: wlan0: probing address 192.168.0.248/24 May 25 03:01:20 volumio systemd[1]: Started dynamicswap service. May 25 03:01:20 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:20 volumio dhcpcd[1085]: wlan0: soliciting an IPv6 router May 25 03:01:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 03:01:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. May 25 03:01:20 volumio systemd[1]: Stopped Volumio Backend Module. May 25 03:01:20 volumio systemd[1]: Started Volumio Backend Module. May 25 03:01:20 volumio systemd[1]: Started dynamicswap service. May 25 03:01:20 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 03:01:20 volumio mpd[25241]: May 25 03:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:01:20 volumio systemd[1]: Started Music Player Daemon. May 25 03:01:21 volumio volumio[25267]: info: ------------------------------------------- May 25 03:01:21 volumio volumio[25267]: info: ----- Volumio3 ---- May 25 03:01:21 volumio volumio[25267]: info: ------------------------------------------- May 25 03:01:21 volumio volumio[25267]: info: ----- System startup ---- May 25 03:01:21 volumio volumio[25267]: info: ------------------------------------------- May 25 03:01:21 volumio volumio-remote-updater[605]: [2025-05-25 03:01:21] [connect] Successful connection May 25 03:01:21 volumio volumio[25267]: info: MYVOLUMIO Environment detected May 25 03:01:21 volumio volumio[25267]: info: Plugin folders cleanup May 25 03:01:21 volumio volumio[25267]: info: Scanning into folder /volumio/app/plugins/ May 25 03:01:21 volumio volumio[25267]: info: Scanning category audio_interface May 25 03:01:21 volumio volumio[25267]: info: Scanning category miscellanea May 25 03:01:21 volumio volumio[25267]: info: Scanning category music_service May 25 03:01:21 volumio volumio[25267]: info: Scanning category plugins.json May 25 03:01:21 volumio volumio[25267]: info: Scanning category system_controller May 25 03:01:21 volumio volumio[25267]: info: Scanning category user_interface May 25 03:01:21 volumio volumio[25267]: info: Scanning into folder /data/plugins/ May 25 03:01:21 volumio volumio[25267]: info: Scanning category music_service May 25 03:01:21 volumio volumio[25267]: info: Scanning category user_interface May 25 03:01:21 volumio volumio[25267]: info: Plugin folders cleanup completed May 25 03:01:21 volumio volumio[25267]: info: ------------------------------------------- May 25 03:01:21 volumio volumio[25267]: info: ----- Core plugins startup ---- May 25 03:01:21 volumio volumio[25267]: info: ------------------------------------------- May 25 03:01:21 volumio volumio[25267]: info: Loading plugins from folder /volumio/app/plugins/ May 25 03:01:21 volumio volumio[25267]: info: Adding plugin upnp to MyMusic Plugins May 25 03:01:21 volumio volumio[25267]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 03:01:21 volumio volumio[25267]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 03:01:21 volumio volumio[25267]: info: Loading plugins from folder /data/plugins/ May 25 03:01:21 volumio volumio[25267]: info: Loading plugin "system"... May 25 03:01:21 volumio volumio[25267]: info: Loading plugin "appearance"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "network"... May 25 03:01:22 volumio volumio[25267]: info: Refreshing Cached IP Addresses May 25 03:01:22 volumio sudo[25297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 03:01:22 volumio sudo[25297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:22 volumio sudo[25297]: pam_unix(sudo:session): session closed for user root May 25 03:01:22 volumio sudo[25299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 03:01:22 volumio sudo[25299]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:22 volumio sudo[25299]: pam_unix(sudo:session): session closed for user root May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "services"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "alsa_controller"... May 25 03:01:22 volumio sudo[25311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 03:01:22 volumio sudo[25311]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:22 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "wizard"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "networkfs"... May 25 03:01:22 volumio volumio[25267]: info: Starting Udev Watcher for removable devices May 25 03:01:22 volumio volumio[25267]: info: Ignoring mount for partition: boot May 25 03:01:22 volumio volumio[25267]: info: Ignoring mount for partition: volumio May 25 03:01:22 volumio volumio[25267]: info: Ignoring mount for partition: volumio_data May 25 03:01:22 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "volumio_command_line_client"... May 25 03:01:22 volumio volumio[25267]: info: Plugin upnp is not enabled May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "my_music"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "mpd"... May 25 03:01:22 volumio volumio[25267]: info: Plugin upnp_browser is not enabled May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "alarm-clock"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "airplay_emulation"... May 25 03:01:22 volumio volumio[25267]: info: Starting Shairport Sync May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "last_100"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "webradio"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "i2s_dacs"... May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "volumiodiscovery"... May 25 03:01:22 volumio volumio[25267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:22 volumio volumio[25267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:22 volumio node[25267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 03:01:22 volumio volumio[25267]: *** WARNING *** For more information see May 25 03:01:22 volumio volumio[25267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:22 volumio volumio[25267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:22 volumio volumio[25267]: *** WARNING *** For more information see May 25 03:01:22 volumio node[25267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:22 volumio node[25267]: *** WARNING *** For more information see May 25 03:01:22 volumio node[25267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 03:01:22 volumio node[25267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 03:01:22 volumio node[25267]: *** WARNING *** For more information see May 25 03:01:22 volumio volumio[25267]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 03:01:22 volumio volumio[25267]: info: Discovery: Started advertising with name: VOLUMIO May 25 03:01:22 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 03:01:22 volumio volumio[25267]: info: Loading plugin "squeezelite_mc"... May 25 03:01:23 volumio volumio[25267]: info: Loading plugin "outputs"... May 25 03:01:23 volumio volumio[25267]: info: Loading plugin "albumart"... May 25 03:01:23 volumio volumio[25267]: info: Plugin example_plugin is not enabled May 25 03:01:23 volumio volumio[25267]: info: Loading plugin "inputs"... May 25 03:01:23 volumio volumio[25267]: info: Loading plugin "updater_comm"... May 25 03:01:23 volumio volumio[25267]: info: Plugin mpdemulation is not enabled May 25 03:01:23 volumio volumio[25267]: info: Loading plugin "rest_api"... May 25 03:01:23 volumio volumio[25267]: info: Loading plugin "websocket"... May 25 03:01:23 volumio volumio[25267]: info: Starting Socket.io Server version 2.3.0 May 25 03:01:23 volumio volumio[25267]: info: Loading plugin "Systeminfo"... May 25 03:01:23 volumio volumio[25267]: Forking 3 albumart workers May 25 03:01:23 volumio volumio[25267]: info: Loading i18n strings for locale en May 25 03:01:23 volumio volumio[25267]: Updating browse sources language May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::initPlayerControls May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 03:01:23 volumio volumio[25267]: Express server listening on port 3000 May 25 03:01:23 volumio volumio[25267]: [Metrics] WebUI: 2s 938.41ms May 25 03:01:23 volumio volumio[25267]: info: CoreStateMachine::resetVolumioState May 25 03:01:23 volumio volumio[25267]: info: CoreStateMachine::getcurrentVolume May 25 03:01:23 volumio volumio[25267]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:24 volumio volumio[25267]: info: Volumio Network Manager: Network status updated: 0 May 25 03:01:24 volumio volumio-remote-updater[605]: [2025-05-25 03:01:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748113281 101 May 25 03:01:24 volumio volumio[25267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:24 volumio volumio[25267]: info: Reloading queue from file May 25 03:01:24 volumio volumio[25267]: info: CoreStateMachine::setRepeat null single undefined May 25 03:01:24 volumio volumio[25267]: info: CoreStateMachine::pushState May 25 03:01:24 volumio volumio[25267]: info: CorePlayQueue::getTrack 0 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::volumioPushState May 25 03:01:24 volumio volumio[25267]: info: CoreStateMachine::setRandom null May 25 03:01:24 volumio volumio[25267]: info: CoreStateMachine::pushState May 25 03:01:24 volumio volumio[25267]: info: CorePlayQueue::getTrack 0 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::volumioPushState May 25 03:01:24 volumio volumio[25267]: info: Setting Device type: Raspberry PI May 25 03:01:24 volumio volumio[25267]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:24 volumio volumio[25267]: info: CoreStateMachine::pushState May 25 03:01:24 volumio volumio[25267]: info: CorePlayQueue::getTrack 0 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::volumioPushState May 25 03:01:24 volumio volumio[25267]: info: CoreStateMachine::updateTrackBlock May 25 03:01:24 volumio volumio[25267]: info: CorePlayQueue::getTrackBlock May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:24 volumio volumio[25267]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 03:01:24 volumio volumio[25267]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 25 03:01:24 volumio volumio[25267]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 03:01:24 volumio volumio[25267]: info: Completed loading Core Plugins May 25 03:01:24 volumio volumio[25267]: info: Preparing to generate the ALSA configuration file May 25 03:01:24 volumio volumio[25267]: Starting albumart workers May 25 03:01:24 volumio volumio[25267]: Starting albumart workers May 25 03:01:24 volumio volumio[25267]: info: Asound.conf file unchanged, so no further update is needed May 25 03:01:24 volumio volumio[25267]: info: Output device has changed, restarting MPD May 25 03:01:24 volumio volumio[25267]: Starting albumart workers May 25 03:01:24 volumio sudo[25367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:24 volumio sudo[25367]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:24 volumio volumio[25267]: info: Output device has changed, restarting Shairport Sync May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:24 volumio sudo[25367]: pam_unix(sudo:session): session closed for user root May 25 03:01:24 volumio sudo[25369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:24 volumio sudo[25369]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:24 volumio volumio[25267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:24 volumio volumio[25267]: info: ___________ START PLUGINS ___________ May 25 03:01:24 volumio volumio[25267]: info: ControllerMpd::onStart: Initializing MPD May 25 03:01:24 volumio volumio[25267]: info: Creating MPD Configuration file May 25 03:01:24 volumio systemd[1]: Stopping Music Player Daemon... May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:24 volumio sudo[25377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 03:01:24 volumio systemd[1]: mpd.service: Succeeded. May 25 03:01:24 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:01:24 volumio sudo[25377]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:24 volumio sudo[25378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 03:01:24 volumio volumio[25267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:24 volumio systemd[1]: Starting Music Player Daemon... May 25 03:01:24 volumio sudo[25378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:24 volumio sudo[25377]: pam_unix(sudo:session): session closed for user root May 25 03:01:24 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 25 03:01:24 volumio systemd[1]: mpd.service: Succeeded. May 25 03:01:24 volumio systemd[1]: Stopped Music Player Daemon. May 25 03:01:24 volumio volumio[25267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:24 volumio systemd[1]: Starting Music Player Daemon... May 25 03:01:24 volumio volumio[25267]: info: [1748113284584] CoreMusicLibrary::Adding element Last_100 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 03:01:24 volumio volumio[25267]: info: [1748113284598] CoreMusicLibrary::Adding element Webradio May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:24 volumio volumio[25267]: info: Initializing BBC Radios May 25 03:01:24 volumio sudo[25388]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 03:01:24 volumio sudo[25388]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 03:01:24 volumio sudo[25388]: pam_unix(sudo:session): session closed for user root May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:24 volumio volumio[25267]: info: [squeezelite_mc] Starting proxy server... May 25 03:01:24 volumio volumio[25267]: info: Volumio Calling Home May 25 03:01:24 volumio volumio[25267]: info: [squeezelite_mc] Proxy server started on port 37317 May 25 03:01:24 volumio volumio[25267]: info: CoreCommandRouter::volumioRetrievevolume May 25 03:01:25 volumio volumio[25267]: info: Discovery: adding 92d514c2-05e0-4590-90ec-0ae0399380af May 25 03:01:25 volumio volumio[25267]: info: Discovery: Found device VOLUMIO May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::volumioGetState May 25 03:01:25 volumio volumio[25267]: info: CorePlayQueue::getTrack 0 May 25 03:01:25 volumio volumio[25267]: info: MPD Permissions set May 25 03:01:25 volumio volumio[25267]: info: MPD Permissions set May 25 03:01:25 volumio volumio[25267]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:25 volumio volumio[25267]: info: CoreStateMachine::pushState May 25 03:01:25 volumio volumio[25267]: info: CorePlayQueue::getTrack 0 May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::volumioPushState May 25 03:01:25 volumio volumio[25267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: VolumeController:: Volume=79 Mute =false May 25 03:01:25 volumio volumio[25267]: info: CoreStateMachine::pushState May 25 03:01:25 volumio volumio[25267]: info: CorePlayQueue::getTrack 0 May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::volumioPushState May 25 03:01:25 volumio volumio[25267]: info: [squeezelite_mc] Server discovery started May 25 03:01:25 volumio volumio[25267]: info: [squeezelite_mc] Player finder started May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 03:01:25 volumio volumio[25267]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 25 03:01:25 volumio dhcpcd[1085]: wlan0: leased 192.168.0.248 for 43200 seconds May 25 03:01:25 volumio avahi-daemon[600]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.248. May 25 03:01:25 volumio avahi-daemon[600]: New relevant interface wlan0.IPv4 for mDNS. May 25 03:01:25 volumio avahi-daemon[600]: Registering new address record for 192.168.0.248 on wlan0.IPv4. May 25 03:01:25 volumio dhcpcd[1085]: wlan0: adding route to 192.168.0.0/24 May 25 03:01:25 volumio volumio[25267]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:25 volumio dhcpcd[1085]: wlan0: adding default route via 192.168.0.1 May 25 03:01:25 volumio volumio[25267]: Error: send ENETUNREACH 255.255.255.255:3483 May 25 03:01:25 volumio volumio[25267]: at doSend (dgram.js:692:16) May 25 03:01:25 volumio volumio[25267]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 25 03:01:25 volumio volumio[25267]: at afterDns (dgram.js:638:5) May 25 03:01:25 volumio volumio[25267]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 25 03:01:25 volumio volumio[25267]: errno: -101, May 25 03:01:25 volumio volumio[25267]: code: 'ENETUNREACH', May 25 03:01:25 volumio volumio[25267]: syscall: 'send', May 25 03:01:25 volumio volumio[25267]: address: '255.255.255.255', May 25 03:01:25 volumio volumio[25267]: port: 3483 May 25 03:01:25 volumio volumio[25267]: } May 25 03:01:25 volumio volumio[25267]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 03:01:25 volumio mpd[25392]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 03:01:25 volumio mpd[25392]: output: No 'audio_output' defined in config file May 25 03:01:25 volumio mpd[25392]: output: Successfully detected a sndio audio device May 25 03:01:25 volumio mpd[25392]: zeroconf: No global port, disabling zeroconf May 25 03:01:25 volumio systemd[1]: Started Music Player Daemon. May 25 03:01:25 volumio sudo[25369]: pam_unix(sudo:session): session closed for user root May 25 03:01:25 volumio sudo[25378]: pam_unix(sudo:session): session closed for user root May 25 03:01:25 volumio sudo[25473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 03:00 May 25 03:01:25 volumio sudo[25473]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"