-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Tue 2025-06-24 16:51:26 EEST. -- Jun 24 16:50:08 volumio wpa_supplicant[1064]: RRM: Ignoring radio measurement request: Not RRM network Jun 24 16:50:50 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-DISCONNECTED bssid=10:7c:61:3b:6d:7c reason=0 locally_generated=1 Jun 24 16:50:50 volumio dhcpcd[1200]: wlan0: carrier lost Jun 24 16:50:50 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 24 16:50:50 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=RO Jun 24 16:50:50 volumio avahi-daemon[836]: Withdrawing address record for 192.168.1.6 on wlan0. Jun 24 16:50:50 volumio avahi-daemon[836]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.6. Jun 24 16:50:50 volumio dhcpcd[1200]: wlan0: deleting route to 192.168.1.0/24 Jun 24 16:50:50 volumio avahi-daemon[836]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 24 16:50:50 volumio volumio[2086]: info: Discovery: A device disappeared from network Jun 24 16:50:52 volumio ntpd[984]: Deleting interface #324 wlan0, 192.168.1.6#123, interface stats: received=0, sent=0, dropped=0, active_time=2210 secs Jun 24 16:50:55 volumio volumio[2086]: info: Volumio Network Manager: Network status updated: 1 Jun 24 16:50:56 volumio wpa_supplicant[1064]: wlan0: Trying to associate with SSID 'ANUS' Jun 24 16:50:57 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 24 16:51:03 volumio wpa_supplicant[1064]: wlan0: Trying to associate with SSID 'ANUS' Jun 24 16:51:03 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 24 16:51:05 volumio volumio[2086]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:05 volumio volumio[2086]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 24 16:51:05 volumio volumio[2086]: at doSend (dgram.js:692:16) Jun 24 16:51:05 volumio volumio[2086]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 24 16:51:05 volumio volumio[2086]: at afterDns (dgram.js:638:5) Jun 24 16:51:05 volumio volumio[2086]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 24 16:51:05 volumio volumio[2086]: errno: -101, Jun 24 16:51:05 volumio volumio[2086]: code: 'ENETUNREACH', Jun 24 16:51:05 volumio volumio[2086]: syscall: 'send', Jun 24 16:51:05 volumio volumio[2086]: address: '255.255.255.255', Jun 24 16:51:05 volumio volumio[2086]: port: 3483 Jun 24 16:51:05 volumio volumio[2086]: } Jun 24 16:51:05 volumio volumio[2086]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:05 volumio sudo[7886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 16:50 Jun 24 16:51:05 volumio sudo[7886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:05 volumio sudo[7886]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:05 volumio volumio-remote-updater[823]: [2025-06-24 16:51:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 24 16:51:05 volumio volumio-remote-updater[823]: [2025-06-24 16:51:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 24 16:51:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 24 16:51:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 24 16:51:05 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:05 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 24 16:51:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jun 24 16:51:05 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:05 volumio systemd[1]: Stopped Volumio Backend Module. Jun 24 16:51:05 volumio systemd[1]: Started Volumio Backend Module. Jun 24 16:51:05 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:05 volumio volumio[7900]: info: ------------------------------------------- Jun 24 16:51:05 volumio volumio[7900]: info: ----- Volumio3 ---- Jun 24 16:51:05 volumio volumio[7900]: info: ------------------------------------------- Jun 24 16:51:05 volumio volumio[7900]: info: ----- System startup ---- Jun 24 16:51:05 volumio volumio[7900]: info: ------------------------------------------- Jun 24 16:51:05 volumio volumio[7900]: info: MYVOLUMIO Environment detected Jun 24 16:51:05 volumio volumio[7900]: info: Plugin folders cleanup Jun 24 16:51:05 volumio volumio[7900]: info: Scanning into folder /volumio/app/plugins/ Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category audio_interface Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category miscellanea Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category music_service Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category plugins.json Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category system_controller Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category user_interface Jun 24 16:51:05 volumio volumio[7900]: info: Scanning into folder /data/plugins/ Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category audio_interface Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category music_service Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category system_controller Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category system_hardware Jun 24 16:51:05 volumio volumio[7900]: info: Scanning category user_interface Jun 24 16:51:05 volumio volumio[7900]: info: Plugin folders cleanup completed Jun 24 16:51:05 volumio volumio[7900]: info: ------------------------------------------- Jun 24 16:51:05 volumio volumio[7900]: info: ----- Core plugins startup ---- Jun 24 16:51:05 volumio volumio[7900]: info: ------------------------------------------- Jun 24 16:51:05 volumio volumio[7900]: info: Loading plugins from folder /volumio/app/plugins/ Jun 24 16:51:05 volumio volumio[7900]: info: Adding plugin upnp to MyMusic Plugins Jun 24 16:51:05 volumio volumio[7900]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 24 16:51:05 volumio volumio[7900]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 24 16:51:05 volumio volumio[7900]: info: Loading plugins from folder /data/plugins/ Jun 24 16:51:05 volumio volumio[7900]: info: Loading plugin "system"... Jun 24 16:51:05 volumio volumio[7900]: info: Loading plugin "appearance"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "network"... Jun 24 16:51:06 volumio volumio[7900]: info: Refreshing Cached IP Addresses Jun 24 16:51:06 volumio sudo[7927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 16:51:06 volumio sudo[7927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:06 volumio sudo[7927]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:06 volumio sudo[7930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 16:51:06 volumio sudo[7930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:06 volumio sudo[7930]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "services"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "alsa_controller"... Jun 24 16:51:06 volumio sudo[7942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 24 16:51:06 volumio sudo[7942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:06 volumio sudo[7942]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:06 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "wizard"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "networkfs"... Jun 24 16:51:06 volumio volumio[7900]: info: Cannot mount NAS All at system boot, trial number 1 ,retrying in 5 seconds Jun 24 16:51:06 volumio volumio[7900]: info: Starting Udev Watcher for removable devices Jun 24 16:51:06 volumio volumio[7900]: info: Ignoring mount for partition: boot Jun 24 16:51:06 volumio volumio[7900]: info: Ignoring mount for partition: volumio Jun 24 16:51:06 volumio volumio[7900]: info: Ignoring mount for partition: volumio_data Jun 24 16:51:06 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "volumio_command_line_client"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "upnp"... Jun 24 16:51:06 volumio volumio[7900]: info: [1750773066194] Starting Upmpd Daemon Jun 24 16:51:06 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "my_music"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "mpd"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "upnp_browser"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "alarm-clock"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "airplay_emulation"... Jun 24 16:51:06 volumio volumio[7900]: info: Starting Shairport Sync Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "last_100"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "webradio"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "i2s_dacs"... Jun 24 16:51:06 volumio volumio[7900]: info: I2S DAC not set, start Auto-detection Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "volumiodiscovery"... Jun 24 16:51:06 volumio volumio[7900]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:06 volumio node[7900]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:06 volumio node[7900]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:06 volumio volumio[7900]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:06 volumio volumio[7900]: *** WARNING *** For more information see Jun 24 16:51:06 volumio volumio[7900]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:06 volumio volumio[7900]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:06 volumio volumio[7900]: *** WARNING *** For more information see Jun 24 16:51:06 volumio node[7900]: *** WARNING *** For more information see Jun 24 16:51:06 volumio node[7900]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:06 volumio node[7900]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:06 volumio node[7900]: *** WARNING *** For more information see Jun 24 16:51:06 volumio volumio[7900]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 24 16:51:06 volumio volumio[7900]: info: Discovery: Started advertising with name: Volumio Jun 24 16:51:06 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "squeezelite_mc"... Jun 24 16:51:06 volumio volumio[7900]: info: Loading plugin "ytcr"... Jun 24 16:51:07 volumio volumio[7900]: info: Plugin autostart is not enabled Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "outputs"... Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "albumart"... Jun 24 16:51:07 volumio volumio[7900]: info: Plugin example_plugin is not enabled Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "inputs"... Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "updater_comm"... Jun 24 16:51:07 volumio volumio[7900]: info: Plugin mpdemulation is not enabled Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "rest_api"... Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "websocket"... Jun 24 16:51:07 volumio volumio[7900]: info: Starting Socket.io Server version 2.3.0 Jun 24 16:51:07 volumio volumio[7900]: info: Plugin fusiondsp is not enabled Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "ir_controller"... Jun 24 16:51:07 volumio volumio[7900]: info: Applying required configuration parameters for plugin ir_controller Jun 24 16:51:07 volumio volumio[7900]: info: Loading plugin "touch_display"... Jun 24 16:51:07 volumio volumio[7900]: Forking 3 albumart workers Jun 24 16:51:07 volumio volumio[7900]: info: Applying required configuration parameters for plugin touch_display Jun 24 16:51:07 volumio volumio[7900]: info: Loading i18n strings for locale en Jun 24 16:51:07 volumio volumio[7900]: Updating browse sources language Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::initPlayerControls Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: Express server listening on port 3000 Jun 24 16:51:07 volumio volumio[7900]: [Metrics] WebUI: 1s 923.60ms Jun 24 16:51:07 volumio volumio[7900]: info: CoreStateMachine::resetVolumioState Jun 24 16:51:07 volumio volumio[7900]: info: CoreStateMachine::getcurrentVolume Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:07 volumio volumio[7900]: info: CoreStateMachine::pushState Jun 24 16:51:07 volumio volumio[7900]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:07 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:07 volumio volumio[7900]: info: Volumio Network Manager: Network status updated: 1 Jun 24 16:51:07 volumio volumio[7900]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 24 16:51:07 volumio volumio[7900]: wlan0 Interface doesn't support scanning : Device or resource busy Jun 24 16:51:07 volumio volumio[7900]: info: Cannot use regular scanning, forcing with ap-force Jun 24 16:51:07 volumio sudo[7990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 24 16:51:07 volumio sudo[7990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:07 volumio volumio[7900]: Starting albumart workers Jun 24 16:51:07 volumio volumio[7900]: Starting albumart workers Jun 24 16:51:07 volumio volumio[7900]: Starting albumart workers Jun 24 16:51:10 volumio volumio-remote-updater[823]: [2025-06-24 16:51:10] [connect] Successful connection Jun 24 16:51:12 volumio sudo[7990]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:12 volumio volumio[7900]: info: Cannot mount NAS All at system boot, trial number 2 ,retrying in 5 seconds Jun 24 16:51:12 volumio volumio[7900]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 24 16:51:12 volumio volumio[7900]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 24 16:51:12 volumio volumio[7900]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 24 16:51:12 volumio volumio-remote-updater[823]: [2025-06-24 16:51:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750773070 101 Jun 24 16:51:12 volumio volumio[7900]: 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: 4 Jun 24 16:51:12 volumio volumio[7900]: info: Reloading queue from file Jun 24 16:51:12 volumio volumio[7900]: info: CoreStateMachine::setRepeat true single undefined Jun 24 16:51:12 volumio volumio[7900]: info: CoreStateMachine::pushState Jun 24 16:51:12 volumio volumio[7900]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:12 volumio volumio[7900]: info: CoreStateMachine::setRandom true Jun 24 16:51:12 volumio volumio[7900]: info: CoreStateMachine::pushState Jun 24 16:51:12 volumio volumio[7900]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:12 volumio volumio[7900]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 24 16:51:12 volumio volumio[7900]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Jun 24 16:51:12 volumio volumio[7900]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 24 16:51:12 volumio volumio[7900]: info: Setting Device type: Raspberry PI Jun 24 16:51:12 volumio volumio[7900]: info: Discovery: adding ed2b6824-410b-475c-b0e8-30c940055aad Jun 24 16:51:12 volumio volumio[7900]: info: Discovery: Found device Volumio Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:12 volumio volumio[7900]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:12 volumio volumio[7900]: info: Completed loading Core Plugins Jun 24 16:51:12 volumio volumio[7900]: info: Preparing to generate the ALSA configuration file Jun 24 16:51:12 volumio volumio[7900]: info: Asound.conf file unchanged, so no further update is needed Jun 24 16:51:12 volumio volumio[7900]: info: Output device has changed, restarting MPD Jun 24 16:51:12 volumio sudo[8022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:12 volumio sudo[8022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:12 volumio sudo[8022]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:12 volumio volumio[7900]: info: Output device has changed, restarting Shairport Sync Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:12 volumio sudo[8025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:12 volumio sudo[8025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:12 volumio systemd[1]: Stopping Music Player Daemon... Jun 24 16:51:12 volumio volumio[7900]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:12 volumio volumio[7900]: info: ___________ START PLUGINS ___________ Jun 24 16:51:12 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:51:12 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:51:12 volumio volumio[7900]: info: ControllerMpd::onStart: Initializing MPD Jun 24 16:51:12 volumio volumio[7900]: info: Creating MPD Configuration file Jun 24 16:51:12 volumio sudo[8032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:12 volumio sudo[8032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:12 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:12 volumio volumio[7900]: info: [1750773072831] CoreMusicLibrary::Adding element Media Servers Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:12 volumio sudo[8032]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:12 volumio sudo[8035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:12 volumio sudo[8035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:12 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 24 16:51:12 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:51:12 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:51:12 volumio volumio[7900]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:12 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:51:12 volumio volumio[7900]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:12 volumio volumio[7900]: info: [1750773072873] CoreMusicLibrary::Adding element Last_100 Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:12 volumio volumio[7900]: info: [1750773072874] CoreMusicLibrary::Adding element Webradio Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:12 volumio volumio[7900]: info: Initializing BBC Radios Jun 24 16:51:12 volumio sudo[8040]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:51:12 volumio sudo[8040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:12 volumio sudo[8040]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:12 volumio volumio[7900]: info: [squeezelite_mc] Starting proxy server... Jun 24 16:51:12 volumio volumio[7900]: info: Loading i18n strings for locale en Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:12 volumio volumio[7900]: info: Loading i18n strings for locale en Jun 24 16:51:12 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:12 volumio volumio[7900]: info: Volumio Calling Home Jun 24 16:51:12 volumio volumio[7900]: info: [squeezelite_mc] Proxy server started on port 39547 Jun 24 16:51:12 volumio sudo[8062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jun 24 16:51:12 volumio sudo[8062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:12 volumio sudo[8062]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:12 volumio sudo[8065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 24 16:51:12 volumio sudo[8065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:13 volumio sudo[8068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 24 16:51:13 volumio sudo[8068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:13 volumio systemd[1]: Reloading. Jun 24 16:51:13 volumio sudo[8071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 24 16:51:13 volumio sudo[8071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:13 volumio volumio[7900]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:13 volumio volumio[7900]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:13 volumio volumio[7900]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:13 volumio volumio[7900]: info: CoreStateMachine::pushState Jun 24 16:51:13 volumio volumio[7900]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:13 volumio volumio[7900]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:13 volumio volumio[7900]: info: [squeezelite_mc] Server discovery started Jun 24 16:51:13 volumio volumio[7900]: info: [squeezelite_mc] Player finder started Jun 24 16:51:13 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:13 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:13 volumio volumio[7900]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:13 volumio volumio[7900]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 24 16:51:13 volumio volumio[7900]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:13 volumio volumio[7900]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 24 16:51:13 volumio volumio[7900]: at doSend (dgram.js:692:16) Jun 24 16:51:13 volumio volumio[7900]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 24 16:51:13 volumio volumio[7900]: at afterDns (dgram.js:638:5) Jun 24 16:51:13 volumio volumio[7900]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 24 16:51:13 volumio volumio[7900]: errno: -101, Jun 24 16:51:13 volumio volumio[7900]: code: 'ENETUNREACH', Jun 24 16:51:13 volumio volumio[7900]: syscall: 'send', Jun 24 16:51:13 volumio volumio[7900]: address: '255.255.255.255', Jun 24 16:51:13 volumio volumio[7900]: port: 3483 Jun 24 16:51:13 volumio volumio[7900]: } Jun 24 16:51:13 volumio volumio[7900]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:13 volumio mpd[8046]: Jun 24 16:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 16:51:13 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 24 16:51:13 volumio sudo[8099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 16:50 Jun 24 16:51:13 volumio sudo[8099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:13 volumio sudo[8099]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:13 volumio sudo[8068]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:13 volumio systemd[1]: Reloading. Jun 24 16:51:13 volumio volumio-remote-updater[823]: [2025-06-24 16:51:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 24 16:51:13 volumio volumio-remote-updater[823]: [2025-06-24 16:51:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 24 16:51:13 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 24 16:51:13 volumio systemd[1]: Started Music Player Daemon. Jun 24 16:51:13 volumio sudo[8071]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 24 16:51:13 volumio sudo[8035]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:13 volumio sudo[8065]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:13 volumio sudo[8025]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 24 16:51:13 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:13 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:13 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 24 16:51:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jun 24 16:51:13 volumio systemd[1]: Stopped Volumio Backend Module. Jun 24 16:51:13 volumio systemd[1]: Started Volumio Backend Module. Jun 24 16:51:13 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:13 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:14 volumio volumio[8127]: info: ------------------------------------------- Jun 24 16:51:14 volumio volumio[8127]: info: ----- Volumio3 ---- Jun 24 16:51:14 volumio volumio[8127]: info: ------------------------------------------- Jun 24 16:51:14 volumio volumio[8127]: info: ----- System startup ---- Jun 24 16:51:14 volumio volumio[8127]: info: ------------------------------------------- Jun 24 16:51:14 volumio volumio[8127]: info: MYVOLUMIO Environment detected Jun 24 16:51:14 volumio volumio[8127]: info: Plugin folders cleanup Jun 24 16:51:14 volumio volumio[8127]: info: Scanning into folder /volumio/app/plugins/ Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category audio_interface Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category miscellanea Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category music_service Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category plugins.json Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category system_controller Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category user_interface Jun 24 16:51:14 volumio volumio[8127]: info: Scanning into folder /data/plugins/ Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category audio_interface Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category music_service Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category system_controller Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category system_hardware Jun 24 16:51:14 volumio volumio[8127]: info: Scanning category user_interface Jun 24 16:51:14 volumio volumio[8127]: info: Plugin folders cleanup completed Jun 24 16:51:14 volumio volumio[8127]: info: ------------------------------------------- Jun 24 16:51:14 volumio volumio[8127]: info: ----- Core plugins startup ---- Jun 24 16:51:14 volumio volumio[8127]: info: ------------------------------------------- Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugins from folder /volumio/app/plugins/ Jun 24 16:51:14 volumio volumio[8127]: info: Adding plugin upnp to MyMusic Plugins Jun 24 16:51:14 volumio volumio[8127]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 24 16:51:14 volumio volumio[8127]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugins from folder /data/plugins/ Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "system"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "appearance"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "network"... Jun 24 16:51:14 volumio volumio[8127]: info: Refreshing Cached IP Addresses Jun 24 16:51:14 volumio sudo[8156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 16:51:14 volumio sudo[8156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:14 volumio sudo[8158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 16:51:14 volumio sudo[8158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:14 volumio sudo[8156]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:14 volumio sudo[8158]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "services"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "alsa_controller"... Jun 24 16:51:14 volumio sudo[8167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 24 16:51:14 volumio sudo[8167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:14 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "wizard"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "networkfs"... Jun 24 16:51:14 volumio volumio[8127]: info: Cannot mount NAS All at system boot, trial number 1 ,retrying in 5 seconds Jun 24 16:51:14 volumio volumio[8127]: info: Starting Udev Watcher for removable devices Jun 24 16:51:14 volumio volumio[8127]: info: Ignoring mount for partition: boot Jun 24 16:51:14 volumio volumio[8127]: info: Ignoring mount for partition: volumio Jun 24 16:51:14 volumio volumio[8127]: info: Ignoring mount for partition: volumio_data Jun 24 16:51:14 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "volumio_command_line_client"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "upnp"... Jun 24 16:51:14 volumio volumio[8127]: info: [1750773074451] Starting Upmpd Daemon Jun 24 16:51:14 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "my_music"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "mpd"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "upnp_browser"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "alarm-clock"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "airplay_emulation"... Jun 24 16:51:14 volumio volumio[8127]: info: Starting Shairport Sync Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "last_100"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "webradio"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "i2s_dacs"... Jun 24 16:51:14 volumio volumio[8127]: info: I2S DAC not set, start Auto-detection Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "volumiodiscovery"... Jun 24 16:51:14 volumio volumio[8127]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:14 volumio node[8127]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:14 volumio node[8127]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:14 volumio volumio[8127]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:14 volumio volumio[8127]: *** WARNING *** For more information see Jun 24 16:51:14 volumio volumio[8127]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:14 volumio volumio[8127]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:14 volumio volumio[8127]: *** WARNING *** For more information see Jun 24 16:51:14 volumio node[8127]: *** WARNING *** For more information see Jun 24 16:51:14 volumio node[8127]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:14 volumio node[8127]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:14 volumio node[8127]: *** WARNING *** For more information see Jun 24 16:51:14 volumio volumio[8127]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 24 16:51:14 volumio volumio[8127]: info: Discovery: Started advertising with name: Volumio Jun 24 16:51:14 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "squeezelite_mc"... Jun 24 16:51:14 volumio volumio[8127]: info: Loading plugin "ytcr"... Jun 24 16:51:15 volumio volumio[8127]: info: Plugin autostart is not enabled Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "outputs"... Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "albumart"... Jun 24 16:51:15 volumio volumio[8127]: info: Plugin example_plugin is not enabled Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "inputs"... Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "updater_comm"... Jun 24 16:51:15 volumio volumio[8127]: info: Plugin mpdemulation is not enabled Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "rest_api"... Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "websocket"... Jun 24 16:51:15 volumio volumio[8127]: info: Starting Socket.io Server version 2.3.0 Jun 24 16:51:15 volumio volumio[8127]: info: Plugin fusiondsp is not enabled Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "ir_controller"... Jun 24 16:51:15 volumio volumio[8127]: info: Applying required configuration parameters for plugin ir_controller Jun 24 16:51:15 volumio volumio[8127]: info: Loading plugin "touch_display"... Jun 24 16:51:15 volumio volumio[8127]: Forking 3 albumart workers Jun 24 16:51:15 volumio volumio[8127]: info: Applying required configuration parameters for plugin touch_display Jun 24 16:51:15 volumio volumio[8127]: info: Loading i18n strings for locale en Jun 24 16:51:15 volumio volumio[8127]: Updating browse sources language Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::initPlayerControls Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: Express server listening on port 3000 Jun 24 16:51:15 volumio volumio[8127]: [Metrics] WebUI: 1s 926.75ms Jun 24 16:51:15 volumio volumio[8127]: info: CoreStateMachine::resetVolumioState Jun 24 16:51:15 volumio volumio[8127]: info: CoreStateMachine::getcurrentVolume Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:15 volumio volumio[8127]: info: CoreStateMachine::pushState Jun 24 16:51:15 volumio volumio[8127]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:15 volumio volumio[8127]: Starting albumart workers Jun 24 16:51:15 volumio volumio[8127]: Starting albumart workers Jun 24 16:51:15 volumio volumio[8127]: Starting albumart workers Jun 24 16:51:15 volumio volumio[8127]: info: Reloading queue from file Jun 24 16:51:15 volumio volumio[8127]: info: CoreStateMachine::setRepeat true single undefined Jun 24 16:51:15 volumio volumio[8127]: info: CoreStateMachine::pushState Jun 24 16:51:15 volumio volumio[8127]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:15 volumio volumio[8127]: info: CoreStateMachine::setRandom true Jun 24 16:51:15 volumio volumio[8127]: info: CoreStateMachine::pushState Jun 24 16:51:15 volumio volumio[8127]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:15 volumio volumio[8127]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:15 volumio volumio[8127]: info: Setting Device type: Raspberry PI Jun 24 16:51:15 volumio volumio[8127]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 24 16:51:15 volumio volumio[8127]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Jun 24 16:51:15 volumio volumio[8127]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 24 16:51:15 volumio wpa_supplicant[1064]: wlan0: Trying to associate with SSID 'ANUS' Jun 24 16:51:15 volumio volumio[8127]: info: Completed loading Core Plugins Jun 24 16:51:15 volumio volumio[8127]: info: Preparing to generate the ALSA configuration file Jun 24 16:51:15 volumio volumio[8127]: info: Volumio Network Manager: Network status updated: 1 Jun 24 16:51:15 volumio volumio[8127]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 24 16:51:16 volumio volumio[8127]: info: Discovery: adding ed2b6824-410b-475c-b0e8-30c940055aad Jun 24 16:51:16 volumio volumio[8127]: info: Discovery: Found device Volumio Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:16 volumio volumio[8127]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:16 volumio volumio[8127]: info: Asound.conf file unchanged, so no further update is needed Jun 24 16:51:16 volumio volumio[8127]: info: Output device has changed, restarting MPD Jun 24 16:51:16 volumio sudo[8222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:16 volumio sudo[8222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio volumio[8127]: info: Output device has changed, restarting Shairport Sync Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:16 volumio sudo[8222]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio sudo[8229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:16 volumio sudo[8229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio systemd[1]: Stopping Music Player Daemon... Jun 24 16:51:16 volumio volumio[8127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:16 volumio volumio[8127]: info: ___________ START PLUGINS ___________ Jun 24 16:51:16 volumio volumio[8127]: info: ControllerMpd::onStart: Initializing MPD Jun 24 16:51:16 volumio volumio[8127]: info: Creating MPD Configuration file Jun 24 16:51:16 volumio sudo[8255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:16 volumio sudo[8255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio sudo[8255]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:16 volumio volumio[8127]: info: [1750773076100] CoreMusicLibrary::Adding element Media Servers Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:16 volumio sudo[8258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:16 volumio sudo[8258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:16 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:51:16 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:51:16 volumio volumio[8127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:16 volumio volumio[8127]: info: [1750773076142] CoreMusicLibrary::Adding element Last_100 Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:16 volumio volumio[8127]: info: [1750773076143] CoreMusicLibrary::Adding element Webradio Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:16 volumio volumio[8127]: info: Initializing BBC Radios Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:16 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:51:16 volumio volumio[8127]: info: [squeezelite_mc] Starting proxy server... Jun 24 16:51:16 volumio volumio[8127]: info: Loading i18n strings for locale en Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:16 volumio volumio[8127]: info: Loading i18n strings for locale en Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:16 volumio volumio[8127]: info: Volumio Calling Home Jun 24 16:51:16 volumio sudo[8266]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:51:16 volumio sudo[8266]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio sudo[8266]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio sudo[8167]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio volumio[8127]: info: [squeezelite_mc] Proxy server started on port 44669 Jun 24 16:51:16 volumio sudo[8285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jun 24 16:51:16 volumio sudo[8285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio sudo[8285]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio sudo[8288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 24 16:51:16 volumio sudo[8288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio sudo[8291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 24 16:51:16 volumio sudo[8291]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio sudo[8294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 24 16:51:16 volumio sudo[8294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio systemd[1]: Reloading. Jun 24 16:51:16 volumio sudo[8288]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:16 volumio volumio[8127]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:16 volumio volumio[8127]: info: CoreStateMachine::pushState Jun 24 16:51:16 volumio volumio[8127]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:16 volumio volumio[8127]: info: [squeezelite_mc] Server discovery started Jun 24 16:51:16 volumio volumio[8127]: info: [squeezelite_mc] Player finder started Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:16 volumio volumio[8127]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 24 16:51:16 volumio volumio[8127]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:16 volumio volumio[8127]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 24 16:51:16 volumio volumio[8127]: at doSend (dgram.js:692:16) Jun 24 16:51:16 volumio volumio[8127]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 24 16:51:16 volumio volumio[8127]: at afterDns (dgram.js:638:5) Jun 24 16:51:16 volumio volumio[8127]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 24 16:51:16 volumio volumio[8127]: errno: -101, Jun 24 16:51:16 volumio volumio[8127]: code: 'ENETUNREACH', Jun 24 16:51:16 volumio volumio[8127]: syscall: 'send', Jun 24 16:51:16 volumio volumio[8127]: address: '255.255.255.255', Jun 24 16:51:16 volumio volumio[8127]: port: 3483 Jun 24 16:51:16 volumio volumio[8127]: } Jun 24 16:51:16 volumio volumio[8127]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:16 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 24 16:51:16 volumio mpd[8280]: Jun 24 16:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 16:51:16 volumio sudo[8320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 16:50 Jun 24 16:51:16 volumio sudo[8320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:16 volumio sudo[8320]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 24 16:51:16 volumio sudo[8291]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio sudo[8258]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio sudo[8229]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio sudo[8294]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:16 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 24 16:51:16 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:16 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:16 volumio dhcpcd[1200]: wlan0: carrier acquired Jun 24 16:51:16 volumio wpa_supplicant[1064]: wlan0: Associated with 10:7c:61:3b:6d:7c Jun 24 16:51:16 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:7c:61:3b:6d:7c completed [id=0 id_str=] Jun 24 16:51:16 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 24 16:51:16 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 24 16:51:16 volumio dhcpcd[1200]: wlan0: IAID 67:23:78:80 Jun 24 16:51:16 volumio dhcpcd[1200]: wlan0: carrier lost Jun 24 16:51:16 volumio dhcpcd[1200]: wlan0: carrier acquired Jun 24 16:51:16 volumio dhcpcd[1200]: wlan0: IAID 67:23:78:80 Jun 24 16:51:16 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 24 16:51:16 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jun 24 16:51:16 volumio systemd[1]: Started Music Player Daemon. Jun 24 16:51:16 volumio systemd[1]: Stopped Volumio Backend Module. Jun 24 16:51:16 volumio systemd[1]: Started Volumio Backend Module. Jun 24 16:51:16 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:16 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:17 volumio volumio[8354]: info: ------------------------------------------- Jun 24 16:51:17 volumio volumio[8354]: info: ----- Volumio3 ---- Jun 24 16:51:17 volumio volumio[8354]: info: ------------------------------------------- Jun 24 16:51:17 volumio volumio[8354]: info: ----- System startup ---- Jun 24 16:51:17 volumio volumio[8354]: info: ------------------------------------------- Jun 24 16:51:17 volumio dhcpcd[1200]: wlan0: rebinding lease of 192.168.1.6 Jun 24 16:51:17 volumio volumio[8354]: info: MYVOLUMIO Environment detected Jun 24 16:51:17 volumio volumio[8354]: info: Plugin folders cleanup Jun 24 16:51:17 volumio volumio[8354]: info: Scanning into folder /volumio/app/plugins/ Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category audio_interface Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category miscellanea Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category music_service Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category plugins.json Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category system_controller Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category user_interface Jun 24 16:51:17 volumio volumio[8354]: info: Scanning into folder /data/plugins/ Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category audio_interface Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category music_service Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category system_controller Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category system_hardware Jun 24 16:51:17 volumio volumio[8354]: info: Scanning category user_interface Jun 24 16:51:17 volumio volumio[8354]: info: Plugin folders cleanup completed Jun 24 16:51:17 volumio volumio[8354]: info: ------------------------------------------- Jun 24 16:51:17 volumio volumio[8354]: info: ----- Core plugins startup ---- Jun 24 16:51:17 volumio volumio[8354]: info: ------------------------------------------- Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugins from folder /volumio/app/plugins/ Jun 24 16:51:17 volumio volumio[8354]: info: Adding plugin upnp to MyMusic Plugins Jun 24 16:51:17 volumio volumio[8354]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 24 16:51:17 volumio volumio[8354]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugins from folder /data/plugins/ Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "system"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "appearance"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "network"... Jun 24 16:51:17 volumio volumio[8354]: info: Refreshing Cached IP Addresses Jun 24 16:51:17 volumio sudo[8381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 16:51:17 volumio sudo[8383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 16:51:17 volumio sudo[8383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:17 volumio sudo[8381]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:17 volumio sudo[8381]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:17 volumio sudo[8383]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "services"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "alsa_controller"... Jun 24 16:51:17 volumio sudo[8396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 24 16:51:17 volumio sudo[8396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:17 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "wizard"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "networkfs"... Jun 24 16:51:17 volumio volumio[8354]: info: Cannot mount NAS All at system boot, trial number 1 ,retrying in 5 seconds Jun 24 16:51:17 volumio volumio[8354]: info: Starting Udev Watcher for removable devices Jun 24 16:51:17 volumio volumio[8354]: info: Ignoring mount for partition: boot Jun 24 16:51:17 volumio volumio[8354]: info: Ignoring mount for partition: volumio Jun 24 16:51:17 volumio volumio[8354]: info: Ignoring mount for partition: volumio_data Jun 24 16:51:17 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "volumio_command_line_client"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "upnp"... Jun 24 16:51:17 volumio volumio[8354]: info: [1750773077540] Starting Upmpd Daemon Jun 24 16:51:17 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "my_music"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "mpd"... Jun 24 16:51:17 volumio dhcpcd[1200]: wlan0: soliciting an IPv6 router Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "upnp_browser"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "alarm-clock"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "airplay_emulation"... Jun 24 16:51:17 volumio volumio[8354]: info: Starting Shairport Sync Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "last_100"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "webradio"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "i2s_dacs"... Jun 24 16:51:17 volumio volumio[8354]: info: I2S DAC not set, start Auto-detection Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "volumiodiscovery"... Jun 24 16:51:17 volumio volumio[8354]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:17 volumio volumio[8354]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:17 volumio volumio[8354]: *** WARNING *** For more information see Jun 24 16:51:17 volumio volumio[8354]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:17 volumio node[8354]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:17 volumio volumio[8354]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:17 volumio volumio[8354]: *** WARNING *** For more information see Jun 24 16:51:17 volumio node[8354]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:17 volumio node[8354]: *** WARNING *** For more information see Jun 24 16:51:17 volumio node[8354]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:17 volumio node[8354]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:17 volumio node[8354]: *** WARNING *** For more information see Jun 24 16:51:17 volumio volumio[8354]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 24 16:51:17 volumio volumio[8354]: info: Discovery: Started advertising with name: Volumio Jun 24 16:51:17 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "squeezelite_mc"... Jun 24 16:51:17 volumio volumio[8354]: info: Loading plugin "ytcr"... Jun 24 16:51:18 volumio volumio-remote-updater[823]: [2025-06-24 16:51:18] [connect] Successful connection Jun 24 16:51:18 volumio volumio[8354]: info: Plugin autostart is not enabled Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "outputs"... Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "albumart"... Jun 24 16:51:18 volumio volumio[8354]: info: Plugin example_plugin is not enabled Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "inputs"... Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "updater_comm"... Jun 24 16:51:18 volumio volumio[8354]: info: Plugin mpdemulation is not enabled Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "rest_api"... Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "websocket"... Jun 24 16:51:18 volumio volumio[8354]: info: Starting Socket.io Server version 2.3.0 Jun 24 16:51:18 volumio volumio[8354]: info: Plugin fusiondsp is not enabled Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "ir_controller"... Jun 24 16:51:18 volumio volumio[8354]: info: Applying required configuration parameters for plugin ir_controller Jun 24 16:51:18 volumio volumio[8354]: info: Loading plugin "touch_display"... Jun 24 16:51:18 volumio volumio[8354]: Forking 3 albumart workers Jun 24 16:51:18 volumio volumio[8354]: info: Applying required configuration parameters for plugin touch_display Jun 24 16:51:18 volumio volumio[8354]: info: Loading i18n strings for locale en Jun 24 16:51:18 volumio volumio[8354]: Updating browse sources language Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::initPlayerControls Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: Express server listening on port 3000 Jun 24 16:51:18 volumio volumio[8354]: [Metrics] WebUI: 1s 903.31ms Jun 24 16:51:18 volumio volumio[8354]: info: CoreStateMachine::resetVolumioState Jun 24 16:51:18 volumio volumio[8354]: info: CoreStateMachine::getcurrentVolume Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:18 volumio volumio[8354]: info: CoreStateMachine::pushState Jun 24 16:51:18 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:18 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:18 volumio volumio[8354]: info: Volumio Network Manager: Network status updated: 1 Jun 24 16:51:18 volumio volumio-remote-updater[823]: [2025-06-24 16:51:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750773078 101 Jun 24 16:51:18 volumio volumio[8354]: 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 Jun 24 16:51:18 volumio volumio[8354]: Starting albumart workers Jun 24 16:51:18 volumio volumio[8354]: Starting albumart workers Jun 24 16:51:18 volumio volumio[8354]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 24 16:51:18 volumio volumio[8354]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 24 16:51:19 volumio volumio[8354]: info: Reloading queue from file Jun 24 16:51:19 volumio volumio[8354]: Starting albumart workers Jun 24 16:51:19 volumio volumio[8354]: info: CoreStateMachine::setRepeat true single undefined Jun 24 16:51:19 volumio volumio[8354]: info: CoreStateMachine::pushState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:19 volumio volumio[8354]: info: CoreStateMachine::setRandom true Jun 24 16:51:19 volumio volumio[8354]: info: CoreStateMachine::pushState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:19 volumio volumio[8354]: info: Setting Device type: Raspberry PI Jun 24 16:51:19 volumio volumio[8354]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 24 16:51:19 volumio volumio[8354]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Jun 24 16:51:19 volumio volumio[8354]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioGetVisibleSources Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioGetQueue Jun 24 16:51:19 volumio volumio[8354]: info: CoreStateMachine::getQueue Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getQueue Jun 24 16:51:19 volumio volumio[8354]: info: Listing playlists Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 24 16:51:19 volumio volumio[8354]: info: Received Get System Info Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 16:51:19 volumio volumio[8354]: info: Discovery: Getting this device information Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: Discovery: adding ed2b6824-410b-475c-b0e8-30c940055aad Jun 24 16:51:19 volumio volumio[8354]: info: Discovery: Found device Volumio Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: Completed loading Core Plugins Jun 24 16:51:19 volumio volumio[8354]: info: Preparing to generate the ALSA configuration file Jun 24 16:51:19 volumio volumio[8354]: info: Asound.conf file unchanged, so no further update is needed Jun 24 16:51:19 volumio volumio[8354]: info: Output device has changed, restarting MPD Jun 24 16:51:19 volumio volumio[8354]: info: Output device has changed, restarting Shairport Sync Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:19 volumio sudo[8452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:19 volumio sudo[8452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio sudo[8470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:19 volumio sudo[8452]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio sudo[8470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio systemd[1]: Stopping Music Player Daemon... Jun 24 16:51:19 volumio volumio[8354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:19 volumio volumio[8354]: info: ___________ START PLUGINS ___________ Jun 24 16:51:19 volumio volumio[8354]: info: ControllerMpd::onStart: Initializing MPD Jun 24 16:51:19 volumio volumio[8354]: info: Creating MPD Configuration file Jun 24 16:51:19 volumio sudo[8481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:19 volumio sudo[8481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio sudo[8481]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:19 volumio volumio[8354]: info: [1750773079311] CoreMusicLibrary::Adding element Media Servers Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:19 volumio sudo[8484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:19 volumio sudo[8484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:51:19 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:51:19 volumio volumio[8354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:19 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:51:19 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 24 16:51:19 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:51:19 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:51:19 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:51:19 volumio volumio[8354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:19 volumio volumio[8354]: info: [1750773079369] CoreMusicLibrary::Adding element Last_100 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:19 volumio volumio[8354]: info: [1750773079371] CoreMusicLibrary::Adding element Webradio Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:19 volumio volumio[8354]: info: Initializing BBC Radios Jun 24 16:51:19 volumio sudo[8493]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:51:19 volumio sudo[8493]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio sudo[8493]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: [squeezelite_mc] Starting proxy server... Jun 24 16:51:19 volumio volumio[8354]: info: Loading i18n strings for locale en Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:19 volumio volumio[8354]: info: Loading i18n strings for locale en Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:19 volumio volumio[8354]: info: Volumio Calling Home Jun 24 16:51:19 volumio volumio[8354]: info: [squeezelite_mc] Proxy server started on port 45965 Jun 24 16:51:19 volumio sudo[8512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jun 24 16:51:19 volumio sudo[8512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio sudo[8512]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio sudo[8515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 24 16:51:19 volumio sudo[8515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio sudo[8517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 24 16:51:19 volumio sudo[8517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio systemd[1]: Reloading. Jun 24 16:51:19 volumio sudo[8521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 24 16:51:19 volumio sudo[8521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreStateMachine::pushState Jun 24 16:51:19 volumio volumio[8354]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:19 volumio volumio[8354]: info: [squeezelite_mc] Server discovery started Jun 24 16:51:19 volumio volumio[8354]: info: [squeezelite_mc] Player finder started Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:19 volumio volumio[8354]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 24 16:51:19 volumio volumio[8354]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:19 volumio volumio[8354]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 24 16:51:19 volumio volumio[8354]: at doSend (dgram.js:692:16) Jun 24 16:51:19 volumio volumio[8354]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 24 16:51:19 volumio volumio[8354]: at afterDns (dgram.js:638:5) Jun 24 16:51:19 volumio volumio[8354]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 24 16:51:19 volumio volumio[8354]: errno: -101, Jun 24 16:51:19 volumio volumio[8354]: code: 'ENETUNREACH', Jun 24 16:51:19 volumio volumio[8354]: syscall: 'send', Jun 24 16:51:19 volumio volumio[8354]: address: '255.255.255.255', Jun 24 16:51:19 volumio volumio[8354]: port: 3483 Jun 24 16:51:19 volumio volumio[8354]: } Jun 24 16:51:19 volumio volumio[8354]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:19 volumio mpd[8497]: Jun 24 16:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 16:51:19 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 24 16:51:19 volumio sudo[8547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 16:50 Jun 24 16:51:19 volumio sudo[8547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:19 volumio sudo[8547]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio volumio-remote-updater[823]: [2025-06-24 16:51:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 24 16:51:19 volumio volumio-remote-updater[823]: [2025-06-24 16:51:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 24 16:51:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 24 16:51:19 volumio sudo[8515]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio sudo[8470]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio sudo[8396]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio sudo[8517]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio sudo[8484]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio sudo[8521]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 24 16:51:19 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:19 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 24 16:51:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Jun 24 16:51:19 volumio systemd[1]: Started Music Player Daemon. Jun 24 16:51:19 volumio systemd[1]: Stopped Volumio Backend Module. Jun 24 16:51:19 volumio systemd[1]: Started Volumio Backend Module. Jun 24 16:51:19 volumio systemd[1]: Started dynamicswap service. Jun 24 16:51:20 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 24 16:51:20 volumio volumio[8562]: info: ------------------------------------------- Jun 24 16:51:20 volumio volumio[8562]: info: ----- Volumio3 ---- Jun 24 16:51:20 volumio volumio[8562]: info: ------------------------------------------- Jun 24 16:51:20 volumio volumio[8562]: info: ----- System startup ---- Jun 24 16:51:20 volumio volumio[8562]: info: ------------------------------------------- Jun 24 16:51:20 volumio volumio[8562]: info: MYVOLUMIO Environment detected Jun 24 16:51:20 volumio volumio[8562]: info: Plugin folders cleanup Jun 24 16:51:20 volumio volumio[8562]: info: Scanning into folder /volumio/app/plugins/ Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category audio_interface Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category miscellanea Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category music_service Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category plugins.json Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category system_controller Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category user_interface Jun 24 16:51:20 volumio volumio[8562]: info: Scanning into folder /data/plugins/ Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category audio_interface Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category music_service Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category system_controller Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category system_hardware Jun 24 16:51:20 volumio volumio[8562]: info: Scanning category user_interface Jun 24 16:51:20 volumio volumio[8562]: info: Plugin folders cleanup completed Jun 24 16:51:20 volumio volumio[8562]: info: ------------------------------------------- Jun 24 16:51:20 volumio volumio[8562]: info: ----- Core plugins startup ---- Jun 24 16:51:20 volumio volumio[8562]: info: ------------------------------------------- Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugins from folder /volumio/app/plugins/ Jun 24 16:51:20 volumio volumio[8562]: info: Adding plugin upnp to MyMusic Plugins Jun 24 16:51:20 volumio volumio[8562]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 24 16:51:20 volumio volumio[8562]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugins from folder /data/plugins/ Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "system"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "appearance"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "network"... Jun 24 16:51:20 volumio volumio[8562]: info: Refreshing Cached IP Addresses Jun 24 16:51:20 volumio sudo[8589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 16:51:20 volumio sudo[8589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:20 volumio sudo[8589]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:20 volumio sudo[8591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 16:51:20 volumio sudo[8591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:20 volumio sudo[8591]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "services"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "alsa_controller"... Jun 24 16:51:20 volumio sudo[8600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 24 16:51:20 volumio sudo[8600]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:20 volumio sudo[8600]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:20 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "wizard"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "networkfs"... Jun 24 16:51:20 volumio volumio[8562]: info: Cannot mount NAS All at system boot, trial number 1 ,retrying in 5 seconds Jun 24 16:51:20 volumio volumio[8562]: info: Starting Udev Watcher for removable devices Jun 24 16:51:20 volumio volumio[8562]: info: Ignoring mount for partition: boot Jun 24 16:51:20 volumio volumio[8562]: info: Ignoring mount for partition: volumio Jun 24 16:51:20 volumio volumio[8562]: info: Ignoring mount for partition: volumio_data Jun 24 16:51:20 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "volumio_command_line_client"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "upnp"... Jun 24 16:51:20 volumio volumio[8562]: info: [1750773080765] Starting Upmpd Daemon Jun 24 16:51:20 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "my_music"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "mpd"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "upnp_browser"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "alarm-clock"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "airplay_emulation"... Jun 24 16:51:20 volumio volumio[8562]: info: Starting Shairport Sync Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "last_100"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "webradio"... Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "i2s_dacs"... Jun 24 16:51:20 volumio volumio[8562]: info: I2S DAC not set, start Auto-detection Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "volumiodiscovery"... Jun 24 16:51:20 volumio volumio[8562]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:20 volumio volumio[8562]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:20 volumio volumio[8562]: *** WARNING *** For more information see Jun 24 16:51:20 volumio volumio[8562]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:20 volumio volumio[8562]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:20 volumio volumio[8562]: *** WARNING *** For more information see Jun 24 16:51:20 volumio node[8562]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:20 volumio node[8562]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:20 volumio node[8562]: *** WARNING *** For more information see Jun 24 16:51:20 volumio node[8562]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 16:51:20 volumio node[8562]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 16:51:20 volumio node[8562]: *** WARNING *** For more information see Jun 24 16:51:20 volumio volumio[8562]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 24 16:51:20 volumio volumio[8562]: info: Discovery: Started advertising with name: Volumio Jun 24 16:51:20 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 16:51:20 volumio volumio[8562]: info: Loading plugin "squeezelite_mc"... Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "ytcr"... Jun 24 16:51:21 volumio dhcpcd[1200]: wlan0: probing address 192.168.1.6/24 Jun 24 16:51:21 volumio volumio[8562]: info: Plugin autostart is not enabled Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "outputs"... Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "albumart"... Jun 24 16:51:21 volumio volumio[8562]: info: Plugin example_plugin is not enabled Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "inputs"... Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "updater_comm"... Jun 24 16:51:21 volumio volumio[8562]: info: Plugin mpdemulation is not enabled Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "rest_api"... Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "websocket"... Jun 24 16:51:21 volumio volumio[8562]: info: Starting Socket.io Server version 2.3.0 Jun 24 16:51:21 volumio volumio[8562]: info: Plugin fusiondsp is not enabled Jun 24 16:51:21 volumio volumio[8562]: info: Loading plugin "ir_controller"... Jun 24 16:51:22 volumio volumio[8562]: info: Applying required configuration parameters for plugin ir_controller Jun 24 16:51:22 volumio volumio[8562]: info: Loading plugin "touch_display"... Jun 24 16:51:22 volumio volumio[8562]: Forking 3 albumart workers Jun 24 16:51:22 volumio volumio[8562]: info: Applying required configuration parameters for plugin touch_display Jun 24 16:51:22 volumio volumio[8562]: info: Loading i18n strings for locale en Jun 24 16:51:22 volumio volumio[8562]: Updating browse sources language Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::initPlayerControls Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: Express server listening on port 3000 Jun 24 16:51:22 volumio volumio[8562]: [Metrics] WebUI: 1s 960.84ms Jun 24 16:51:22 volumio volumio[8562]: info: CoreStateMachine::resetVolumioState Jun 24 16:51:22 volumio volumio[8562]: info: CoreStateMachine::getcurrentVolume Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:22 volumio volumio[8562]: info: CoreStateMachine::pushState Jun 24 16:51:22 volumio volumio[8562]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:22 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:22 volumio volumio[8562]: info: Volumio Network Manager: Network status updated: 1 Jun 24 16:51:22 volumio volumio[8562]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 24 16:51:22 volumio volumio[8562]: wlan0 Interface doesn't support scanning : Device or resource busy Jun 24 16:51:22 volumio volumio[8562]: info: Cannot use regular scanning, forcing with ap-force Jun 24 16:51:22 volumio volumio[8562]: Starting albumart workers Jun 24 16:51:22 volumio sudo[8650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 24 16:51:22 volumio sudo[8650]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:22 volumio volumio[8562]: Starting albumart workers Jun 24 16:51:22 volumio volumio[8562]: Starting albumart workers Jun 24 16:51:24 volumio volumio-remote-updater[823]: [2025-06-24 16:51:24] [connect] Successful connection Jun 24 16:51:26 volumio sudo[8650]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:26 volumio volumio[8562]: info: Cannot mount NAS All at system boot, trial number 2 ,retrying in 5 seconds Jun 24 16:51:26 volumio volumio[8562]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 24 16:51:26 volumio volumio[8562]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 24 16:51:26 volumio volumio[8562]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 24 16:51:26 volumio volumio-remote-updater[823]: [2025-06-24 16:51:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750773084 101 Jun 24 16:51:26 volumio volumio[8562]: 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: 4 Jun 24 16:51:26 volumio volumio[8562]: info: Reloading queue from file Jun 24 16:51:26 volumio volumio[8562]: info: CoreStateMachine::setRepeat true single undefined Jun 24 16:51:26 volumio volumio[8562]: info: CoreStateMachine::pushState Jun 24 16:51:26 volumio volumio[8562]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:26 volumio volumio[8562]: info: CoreStateMachine::setRandom true Jun 24 16:51:26 volumio volumio[8562]: info: CoreStateMachine::pushState Jun 24 16:51:26 volumio volumio[8562]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:26 volumio volumio[8562]: info: Setting Device type: Raspberry PI Jun 24 16:51:26 volumio volumio[8562]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 24 16:51:26 volumio volumio[8562]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Jun 24 16:51:26 volumio volumio[8562]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 24 16:51:26 volumio volumio[8562]: info: Discovery: adding ed2b6824-410b-475c-b0e8-30c940055aad Jun 24 16:51:26 volumio volumio[8562]: info: Discovery: Found device Volumio Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:26 volumio volumio[8562]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:26 volumio volumio[8562]: info: Completed loading Core Plugins Jun 24 16:51:26 volumio volumio[8562]: info: Preparing to generate the ALSA configuration file Jun 24 16:51:26 volumio volumio[8562]: info: Asound.conf file unchanged, so no further update is needed Jun 24 16:51:26 volumio volumio[8562]: info: Output device has changed, restarting MPD Jun 24 16:51:26 volumio sudo[8685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:26 volumio sudo[8685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio sudo[8685]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:26 volumio volumio[8562]: info: Output device has changed, restarting Shairport Sync Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:26 volumio sudo[8688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:26 volumio sudo[8688]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio systemd[1]: Stopping Music Player Daemon... Jun 24 16:51:26 volumio volumio[8562]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:26 volumio volumio[8562]: info: ___________ START PLUGINS ___________ Jun 24 16:51:26 volumio volumio[8562]: info: ControllerMpd::onStart: Initializing MPD Jun 24 16:51:26 volumio volumio[8562]: info: Creating MPD Configuration file Jun 24 16:51:26 volumio sudo[8694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:51:26 volumio sudo[8694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio sudo[8694]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:26 volumio volumio[8562]: info: [1750773086157] CoreMusicLibrary::Adding element Media Servers Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:26 volumio sudo[8697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:51:26 volumio sudo[8697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:26 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:51:26 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:51:26 volumio volumio[8562]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:26 volumio volumio[8562]: info: [1750773086203] CoreMusicLibrary::Adding element Last_100 Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 16:51:26 volumio volumio[8562]: info: [1750773086204] CoreMusicLibrary::Adding element Webradio Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:26 volumio volumio[8562]: info: Initializing BBC Radios Jun 24 16:51:26 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: [squeezelite_mc] Starting proxy server... Jun 24 16:51:26 volumio sudo[8705]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:51:26 volumio sudo[8705]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio sudo[8705]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:26 volumio volumio[8562]: info: Loading i18n strings for locale en Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:26 volumio volumio[8562]: info: Loading i18n strings for locale en Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 16:51:26 volumio volumio[8562]: info: Volumio Calling Home Jun 24 16:51:26 volumio volumio[8562]: info: [squeezelite_mc] Proxy server started on port 39945 Jun 24 16:51:26 volumio sudo[8725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jun 24 16:51:26 volumio sudo[8725]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio sudo[8725]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:26 volumio sudo[8728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 24 16:51:26 volumio sudo[8728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio sudo[8731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 24 16:51:26 volumio sudo[8731]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio sudo[8734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 24 16:51:26 volumio sudo[8734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:51:26 volumio systemd[1]: Reloading. Jun 24 16:51:26 volumio sudo[8728]: pam_unix(sudo:session): session closed for user root Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioGetState Jun 24 16:51:26 volumio volumio[8562]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:26 volumio volumio[8562]: info: CoreStateMachine::pushState Jun 24 16:51:26 volumio volumio[8562]: info: CorePlayQueue::getTrack 0 Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::volumioPushState Jun 24 16:51:26 volumio volumio[8562]: info: [squeezelite_mc] Server discovery started Jun 24 16:51:26 volumio volumio[8562]: info: [squeezelite_mc] Player finder started Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:51:26 volumio volumio[8562]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 24 16:51:26 volumio volumio[8562]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:26 volumio volumio[8562]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 24 16:51:26 volumio volumio[8562]: at doSend (dgram.js:692:16) Jun 24 16:51:26 volumio volumio[8562]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 24 16:51:26 volumio volumio[8562]: at afterDns (dgram.js:638:5) Jun 24 16:51:26 volumio volumio[8562]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 24 16:51:26 volumio volumio[8562]: errno: -101, Jun 24 16:51:26 volumio volumio[8562]: code: 'ENETUNREACH', Jun 24 16:51:26 volumio volumio[8562]: syscall: 'send', Jun 24 16:51:26 volumio volumio[8562]: address: '255.255.255.255', Jun 24 16:51:26 volumio volumio[8562]: port: 3483 Jun 24 16:51:26 volumio volumio[8562]: } Jun 24 16:51:26 volumio volumio[8562]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:51:26 volumio dhcpcd[1200]: wlan0: leased 192.168.1.6 for 86400 seconds Jun 24 16:51:26 volumio dhcpcd[1200]: wlan0: adding route to 192.168.1.0/24 Jun 24 16:51:26 volumio dhcpcd[1200]: wlan0: adding default route via 192.168.1.1 Jun 24 16:51:26 volumio avahi-daemon[836]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.6. Jun 24 16:51:26 volumio avahi-daemon[836]: New relevant interface wlan0.IPv4 for mDNS. Jun 24 16:51:26 volumio avahi-daemon[836]: Registering new address record for 192.168.1.6 on wlan0.IPv4. Jun 24 16:51:26 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 24 16:51:26 volumio mpd[8718]: Jun 24 16:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 16:51:26 volumio sudo[8784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 16:50 Jun 24 16:51:26 volumio sudo[8784]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"