-- Logs begin at Thu 2019-02-14 16:11:59 +06, end at Tue 2025-12-16 09:49:06 +06. -- Dec 16 09:48:02 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 16 09:48:02 volumio dhcpcd[760]: wlan0: carrier lost Dec 16 09:48:02 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:c0:92:22:a9:40 reason=4 locally_generated=1 Dec 16 09:48:02 volumio avahi-daemon[631]: Withdrawing address record for 192.168.3.89 on wlan0. Dec 16 09:48:02 volumio avahi-daemon[631]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.89. Dec 16 09:48:02 volumio avahi-daemon[631]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 09:48:02 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 16 09:48:02 volumio volumio[980]: info: Discovery: A device disappeared from network Dec 16 09:48:02 volumio volumio[980]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Dec 16 09:48:02 volumio dhcpcd[760]: wlan0: deleting route to 192.168.3.0/24 Dec 16 09:48:02 volumio dhcpcd[760]: wlan0: deleting default route via 192.168.3.1 Dec 16 09:48:02 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:48:02 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:48:03 volumio wpa_supplicant[892]: wlan0: SME: Trying to authenticate with a8:c0:92:22:a9:40 (SSID='HUAWEI-F1CY67' freq=2462 MHz) Dec 16 09:48:03 volumio kernel: wlan0: authenticate with a8:c0:92:22:a9:40 Dec 16 09:48:03 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Dec 16 09:48:03 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 1/3) Dec 16 09:48:03 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 2/3) Dec 16 09:48:03 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 3/3) Dec 16 09:48:03 volumio kernel: wlan0: authentication with a8:c0:92:22:a9:40 timed out Dec 16 09:48:04 volumio ntpd[1047]: Deleting interface #3 wlan0, 192.168.3.89#123, interface stats: received=195, sent=201, dropped=4, active_time=418 secs Dec 16 09:48:04 volumio ntpd[1047]: 83.243.68.157 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 91.206.16.3 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 212.113.99.6 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 45.141.102.99 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 90.188.9.144 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 46.188.16.150 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 217.170.87.229 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 162.159.200.1 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 89.109.251.22 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 193.192.36.3 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 92.118.113.65 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 93.191.12.44 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 195.122.253.38 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio ntpd[1047]: 87.103.245.205 local addr 192.168.3.89 -> Dec 16 09:48:04 volumio volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 09:48:04 volumio volumio[980]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 09:48:04 volumio volumio[980]: at doSend (dgram.js:714:16) Dec 16 09:48:04 volumio volumio[980]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 16 09:48:04 volumio volumio[980]: at afterDns (dgram.js:660:5) Dec 16 09:48:04 volumio volumio[980]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 16 09:48:04 volumio volumio[980]: errno: -101, Dec 16 09:48:04 volumio volumio[980]: code: 'ENETUNREACH', Dec 16 09:48:04 volumio volumio[980]: syscall: 'send', Dec 16 09:48:04 volumio volumio[980]: address: '255.255.255.255', Dec 16 09:48:04 volumio volumio[980]: port: 3483 Dec 16 09:48:04 volumio volumio[980]: } Dec 16 09:48:04 volumio volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 09:48:05 volumio wpa_supplicant[892]: wlan0: SME: Trying to authenticate with a8:c0:92:22:a9:40 (SSID='HUAWEI-F1CY67' freq=2462 MHz) Dec 16 09:48:05 volumio kernel: wlan0: authenticate with a8:c0:92:22:a9:40 Dec 16 09:48:05 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Dec 16 09:48:05 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 1/3) Dec 16 09:48:05 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 2/3) Dec 16 09:48:05 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 3/3) Dec 16 09:48:05 volumio sudo[2625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 09:47 Dec 16 09:48:05 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:05 volumio kernel: wlan0: authentication with a8:c0:92:22:a9:40 timed out Dec 16 09:48:05 volumio sudo[2625]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:05 volumio volumio-remote-updater[620]: [2025-12-16 09:48:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 09:48:05 volumio volumio-remote-updater[620]: [2025-12-16 09:48:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 09:48:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 09:48:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 09:48:06 volumio systemd[1]: Started dynamicswap service. Dec 16 09:48:06 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 16 09:48:06 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 09:48:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 16 09:48:06 volumio systemd[1]: Stopped Volumio Backend Module. Dec 16 09:48:06 volumio systemd[1]: Started Volumio Backend Module. Dec 16 09:48:06 volumio systemd[1]: Started dynamicswap service. Dec 16 09:48:06 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 16 09:48:08 volumio kernel: perf: interrupt took too long (3956 > 3948), lowering kernel.perf_event_max_sample_rate to 50500 Dec 16 09:48:08 volumio volumio[2638]: info: ------------------------------------------- Dec 16 09:48:08 volumio volumio[2638]: info: ----- Volumio3 ---- Dec 16 09:48:08 volumio volumio[2638]: info: ------------------------------------------- Dec 16 09:48:08 volumio volumio[2638]: info: ----- System startup ---- Dec 16 09:48:08 volumio volumio[2638]: info: ------------------------------------------- Dec 16 09:48:09 volumio volumio[2638]: info: MYVOLUMIO Environment detected Dec 16 09:48:09 volumio volumio[2638]: info: Plugin folders cleanup Dec 16 09:48:09 volumio volumio[2638]: info: Scanning into folder /volumio/app/plugins/ Dec 16 09:48:09 volumio volumio[2638]: info: Scanning category audio_interface Dec 16 09:48:09 volumio volumio[2638]: info: Scanning category miscellanea Dec 16 09:48:09 volumio volumio[2638]: info: Scanning category music_service Dec 16 09:48:09 volumio volumio[2638]: info: Scanning category plugins.json Dec 16 09:48:09 volumio volumio[2638]: info: Scanning category system_controller Dec 16 09:48:09 volumio volumio[2638]: info: Scanning category user_interface Dec 16 09:48:09 volumio volumio[2638]: info: Scanning into folder /data/plugins/ Dec 16 09:48:09 volumio volumio[2638]: info: Scanning category music_service Dec 16 09:48:09 volumio volumio[2638]: info: Plugin folders cleanup completed Dec 16 09:48:09 volumio volumio[2638]: info: ------------------------------------------- Dec 16 09:48:09 volumio volumio[2638]: info: ----- Core plugins startup ---- Dec 16 09:48:09 volumio volumio[2638]: info: ------------------------------------------- Dec 16 09:48:09 volumio volumio[2638]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 09:48:09 volumio volumio[2638]: info: Adding plugin upnp to MyMusic Plugins Dec 16 09:48:09 volumio volumio[2638]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 09:48:09 volumio volumio[2638]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 09:48:09 volumio volumio[2638]: info: Loading plugins from folder /data/plugins/ Dec 16 09:48:09 volumio volumio[2638]: info: Loading plugin "system"... Dec 16 09:48:09 volumio volumio[2638]: info: Loading plugin "appearance"... Dec 16 09:48:10 volumio volumio[2638]: info: Loading plugin "network"... Dec 16 09:48:10 volumio volumio[2638]: info: Refreshing Cached IP Addresses Dec 16 09:48:10 volumio sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 09:48:10 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:10 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:10 volumio sudo[2670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 09:48:10 volumio sudo[2670]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:10 volumio volumio[2638]: info: Loading plugin "services"... Dec 16 09:48:10 volumio sudo[2670]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:10 volumio volumio[2638]: info: Loading plugin "alsa_controller"... Dec 16 09:48:10 volumio sudo[2678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 09:48:10 volumio sudo[2678]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:10 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 09:48:10 volumio volumio[2638]: info: Loading plugin "wizard"... Dec 16 09:48:10 volumio volumio[2638]: info: Loading plugin "networkfs"... Dec 16 09:48:10 volumio volumio[2638]: info: Starting Udev Watcher for removable devices Dec 16 09:48:10 volumio volumio[2638]: info: Ignoring mount for partition: boot Dec 16 09:48:10 volumio volumio[2638]: info: Ignoring mount for partition: volumio Dec 16 09:48:10 volumio volumio[2638]: info: Ignoring mount for partition: volumio_data Dec 16 09:48:10 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 09:48:10 volumio volumio[2638]: info: Loading plugin "volumio_command_line_client"... Dec 16 09:48:10 volumio volumio-remote-updater[620]: [2025-12-16 09:48:10] [connect] Successful connection Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "upnp"... Dec 16 09:48:11 volumio volumio[2638]: info: [1765856891010] Starting Upmpd Daemon Dec 16 09:48:11 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "my_music"... Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "mpd"... Dec 16 09:48:11 volumio volumio[2638]: info: Plugin upnp_browser is not enabled Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "alarm-clock"... Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "airplay_emulation"... Dec 16 09:48:11 volumio volumio[2638]: info: Starting Shairport Sync Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "last_100"... Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "webradio"... Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "i2s_dacs"... Dec 16 09:48:11 volumio volumio[2638]: info: I2S DAC not set, start Auto-detection Dec 16 09:48:11 volumio volumio[2638]: info: Loading plugin "volumiodiscovery"... Dec 16 09:48:11 volumio volumio[2638]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:11 volumio volumio[2638]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:11 volumio node[2638]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:11 volumio volumio[2638]: *** WARNING *** For more information see Dec 16 09:48:11 volumio volumio[2638]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:11 volumio volumio[2638]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:11 volumio volumio[2638]: *** WARNING *** For more information see Dec 16 09:48:11 volumio node[2638]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:11 volumio node[2638]: *** WARNING *** For more information see Dec 16 09:48:11 volumio node[2638]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:11 volumio node[2638]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:11 volumio node[2638]: *** WARNING *** For more information see Dec 16 09:48:11 volumio volumio[2638]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 09:48:11 volumio sudo[2678]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:12 volumio volumio[2638]: info: Discovery: Started advertising with name: Volumio Dec 16 09:48:13 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 09:48:13 volumio volumio[2638]: info: Loading plugin "squeezelite_mc"... Dec 16 09:48:13 volumio kernel: wlan0: authenticate with a8:c0:92:22:a9:40 Dec 16 09:48:13 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Dec 16 09:48:13 volumio wpa_supplicant[892]: wlan0: SME: Trying to authenticate with a8:c0:92:22:a9:40 (SSID='HUAWEI-F1CY67' freq=2462 MHz) Dec 16 09:48:13 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 1/3) Dec 16 09:48:13 volumio wpa_supplicant[892]: wlan0: Trying to associate with a8:c0:92:22:a9:40 (SSID='HUAWEI-F1CY67' freq=2462 MHz) Dec 16 09:48:13 volumio kernel: wlan0: authenticated Dec 16 09:48:13 volumio kernel: wlan0: associate with a8:c0:92:22:a9:40 (try 1/3) Dec 16 09:48:13 volumio kernel: wlan0: RX AssocResp from a8:c0:92:22:a9:40 (capab=0x1431 status=0 aid=3) Dec 16 09:48:13 volumio kernel: wlan0: associated Dec 16 09:48:13 volumio wpa_supplicant[892]: wlan0: Associated with a8:c0:92:22:a9:40 Dec 16 09:48:13 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 09:48:14 volumio wpa_supplicant[892]: wlan0: WPA: Key negotiation completed with a8:c0:92:22:a9:40 [PTK=CCMP GTK=CCMP] Dec 16 09:48:14 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:c0:92:22:a9:40 completed [id=0 id_str=] Dec 16 09:48:14 volumio dhcpcd[760]: wlan0: carrier acquired Dec 16 09:48:14 volumio dhcpcd[760]: wlan0: IAID d6:c1:59:0a Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "outputs"... Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "albumart"... Dec 16 09:48:14 volumio volumio[2638]: info: Plugin example_plugin is not enabled Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "inputs"... Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "updater_comm"... Dec 16 09:48:14 volumio dhcpcd[760]: wlan0: soliciting a DHCP lease Dec 16 09:48:14 volumio dhcpcd[760]: wlan0: soliciting an IPv6 router Dec 16 09:48:14 volumio volumio[2638]: info: Plugin mpdemulation is not enabled Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "rest_api"... Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "websocket"... Dec 16 09:48:14 volumio volumio[2638]: info: Starting Socket.io Server version 2.3.0 Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "RoonBridge"... Dec 16 09:48:14 volumio volumio[2638]: Forking 1 albumart workers Dec 16 09:48:14 volumio volumio[2638]: info: Applying required configuration parameters for plugin RoonBridge Dec 16 09:48:14 volumio volumio[2638]: info: Loading plugin "motherearthradio"... Dec 16 09:48:15 volumio volumio[2638]: info: Applying required configuration parameters for plugin motherearthradio Dec 16 09:48:15 volumio volumio[2638]: info: [1765856895474] [MotherEarth] API delay: 5 Dec 16 09:48:15 volumio volumio[2638]: info: Loading plugin "radio_paradise"... Dec 16 09:48:15 volumio volumio[2638]: Starting albumart workers Dec 16 09:48:15 volumio volumio[2638]: info: Applying required configuration parameters for plugin radio_paradise Dec 16 09:48:15 volumio volumio[2638]: info: [1765856895829] [RadioParadise] API delay: 5 Dec 16 09:48:15 volumio volumio[2638]: info: Loading i18n strings for locale ru Dec 16 09:48:15 volumio volumio[2638]: Updating browse sources language Dec 16 09:48:15 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::initPlayerControls Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 09:48:16 volumio volumio[2638]: Express server listening on port 3000 Dec 16 09:48:16 volumio volumio[2638]: [Metrics] WebUI: 8s 810.60ms Dec 16 09:48:16 volumio volumio[2638]: info: Setting Device type: x86 Dec 16 09:48:16 volumio volumio[2638]: info: CoreStateMachine::resetVolumioState Dec 16 09:48:16 volumio volumio[2638]: info: CoreStateMachine::getcurrentVolume Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 09:48:16 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:17 volumio volumio[2638]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 16 09:48:17 volumio volumio[2638]: info: Completed loading Core Plugins Dec 16 09:48:17 volumio volumio[2638]: info: Preparing to generate the ALSA configuration file Dec 16 09:48:17 volumio volumio[2638]: info: Volumio Network Manager: Network status updated: 0 Dec 16 09:48:17 volumio volumio[2638]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 16 09:48:17 volumio volumio[2638]: info: VolumeController:: Volume=74 Mute =false Dec 16 09:48:17 volumio volumio[2638]: info: CoreStateMachine::pushState Dec 16 09:48:17 volumio volumio[2638]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:17 volumio volumio[2638]: info: CoreStateMachine::updateTrackBlock Dec 16 09:48:17 volumio volumio[2638]: info: CorePlayQueue::getTrackBlock Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 09:48:17 volumio volumio[2638]: info: Reloading queue from file Dec 16 09:48:17 volumio volumio[2638]: info: Asound.conf file unchanged, so no further update is needed Dec 16 09:48:17 volumio volumio[2638]: info: Output device has changed, restarting MPD Dec 16 09:48:17 volumio volumio[2638]: info: Output device has changed, restarting Shairport Sync Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:17 volumio sudo[2730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 09:48:17 volumio sudo[2730]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:17 volumio sudo[2730]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:17 volumio sudo[2732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 09:48:17 volumio sudo[2732]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:17 volumio systemd[1]: Stopping Music Player Daemon... Dec 16 09:48:17 volumio volumio[2638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 09:48:17 volumio volumio[2638]: info: ___________ START PLUGINS ___________ Dec 16 09:48:17 volumio systemd[1]: mpd.service: Succeeded. Dec 16 09:48:17 volumio systemd[1]: Stopped Music Player Daemon. Dec 16 09:48:17 volumio volumio[2638]: info: ControllerMpd::onStart: Initializing MPD Dec 16 09:48:17 volumio volumio[2638]: info: Creating MPD Configuration file Dec 16 09:48:17 volumio systemd[1]: Starting Music Player Daemon... Dec 16 09:48:17 volumio dhcpcd[760]: wlan0: offered 192.168.3.89 from 192.168.3.1 Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:17 volumio sudo[2740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 09:48:17 volumio sudo[2740]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:17 volumio sudo[2743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 09:48:17 volumio sudo[2743]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:17 volumio sudo[2740]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:17 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 09:48:17 volumio systemd[1]: mpd.service: Succeeded. Dec 16 09:48:17 volumio volumio[2638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 09:48:17 volumio systemd[1]: Stopped Music Player Daemon. Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:17 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:18 volumio systemd[1]: Starting Music Player Daemon... Dec 16 09:48:18 volumio volumio[2638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:18 volumio volumio[2638]: info: [1765856898064] CoreMusicLibrary::Adding element Last_100 Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:18 volumio volumio[2638]: info: [1765856898073] CoreMusicLibrary::Adding element Webradio Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 09:48:18 volumio volumio[2638]: info: Initializing BBC Radios Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:18 volumio sudo[2749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 09:48:18 volumio sudo[2749]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:18 volumio sudo[2749]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:18 volumio volumio[2638]: info: [squeezelite_mc] Starting proxy server... Dec 16 09:48:18 volumio sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Dec 16 09:48:18 volumio sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:18 volumio volumio[2638]: info: [1765856898355] CoreMusicLibrary::Adding element Mother Earth Radio Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:18 volumio volumio[2638]: Cannot find translation for source Mother Earth Radio Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:18 volumio volumio[2638]: info: [1765856898393] CoreMusicLibrary::Adding element Radio Paradise Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:18 volumio volumio[2638]: Cannot find translation for source Mother Earth Radio Dec 16 09:48:18 volumio volumio[2638]: Cannot find translation for source Radio Paradise Dec 16 09:48:18 volumio volumio[2638]: info: Volumio Calling Home Dec 16 09:48:18 volumio sudo[2766]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:18 volumio volumio[2638]: info: [squeezelite_mc] Proxy server started on port 34519 Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 09:48:18 volumio volumio[2638]: info: CoreStateMachine::setRepeat null single undefined Dec 16 09:48:18 volumio volumio[2638]: info: CoreStateMachine::pushState Dec 16 09:48:18 volumio volumio[2638]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:18 volumio volumio[2638]: info: CoreStateMachine::setRandom null Dec 16 09:48:18 volumio volumio[2638]: info: CoreStateMachine::pushState Dec 16 09:48:18 volumio volumio[2638]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:18 volumio volumio[2638]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:19 volumio volumio[2638]: info: MPD Permissions set Dec 16 09:48:19 volumio volumio[2638]: info: MPD Permissions set Dec 16 09:48:19 volumio volumio[2638]: info: VolumeController:: Volume=74 Mute =false Dec 16 09:48:19 volumio volumio[2638]: info: CoreStateMachine::pushState Dec 16 09:48:19 volumio volumio[2638]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:19 volumio volumio[2638]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio dhcpcd[760]: wlan0: probing for an IPv4LL address Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: VolumeController:: Volume=74 Mute =false Dec 16 09:48:19 volumio volumio[2638]: info: CoreStateMachine::pushState Dec 16 09:48:19 volumio volumio[2638]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:19 volumio volumio[2638]: info: [squeezelite_mc] Server discovery started Dec 16 09:48:19 volumio volumio[2638]: info: [squeezelite_mc] Player finder started Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:19 volumio volumio[2638]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 09:48:19 volumio volumio[2638]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 09:48:19 volumio volumio[2638]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 09:48:19 volumio volumio[2638]: at doSend (dgram.js:714:16) Dec 16 09:48:19 volumio volumio[2638]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 16 09:48:19 volumio volumio[2638]: at afterDns (dgram.js:660:5) Dec 16 09:48:19 volumio volumio[2638]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 16 09:48:19 volumio volumio[2638]: errno: -101, Dec 16 09:48:19 volumio volumio[2638]: code: 'ENETUNREACH', Dec 16 09:48:19 volumio volumio[2638]: syscall: 'send', Dec 16 09:48:19 volumio volumio[2638]: address: '255.255.255.255', Dec 16 09:48:19 volumio volumio[2638]: port: 3483 Dec 16 09:48:19 volumio volumio[2638]: } Dec 16 09:48:19 volumio volumio[2638]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 09:48:20 volumio sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 09:47 Dec 16 09:48:20 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:20 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 09:48:20 volumio sudo[2743]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:20 volumio sudo[2732]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 09:48:20 volumio systemd[1]: Started dynamicswap service. Dec 16 09:48:21 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 16 09:48:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 09:48:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Dec 16 09:48:21 volumio systemd[1]: Stopped Volumio Backend Module. Dec 16 09:48:21 volumio systemd[1]: Started Volumio Backend Module. Dec 16 09:48:21 volumio systemd[1]: Started dynamicswap service. Dec 16 09:48:21 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 16 09:48:21 volumio mpd[2764]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 09:48:21 volumio mpd[2764]: output: No 'audio_output' defined in config file Dec 16 09:48:21 volumio mpd[2764]: output: Successfully detected a sndio audio device Dec 16 09:48:21 volumio mpd[2764]: zeroconf: No global port, disabling zeroconf Dec 16 09:48:21 volumio systemd[1]: Started Music Player Daemon. Dec 16 09:48:22 volumio dhcpcd[760]: wlan0: probing address 192.168.3.89/24 Dec 16 09:48:23 volumio volumio[2821]: info: ------------------------------------------- Dec 16 09:48:23 volumio volumio[2821]: info: ----- Volumio3 ---- Dec 16 09:48:23 volumio volumio[2821]: info: ------------------------------------------- Dec 16 09:48:23 volumio volumio[2821]: info: ----- System startup ---- Dec 16 09:48:23 volumio volumio[2821]: info: ------------------------------------------- Dec 16 09:48:23 volumio volumio-remote-updater[620]: [2025-12-16 09:48:23] [connect] Successful connection Dec 16 09:48:24 volumio volumio[2821]: info: MYVOLUMIO Environment detected Dec 16 09:48:24 volumio volumio[2821]: info: Plugin folders cleanup Dec 16 09:48:24 volumio volumio[2821]: info: Scanning into folder /volumio/app/plugins/ Dec 16 09:48:24 volumio volumio[2821]: info: Scanning category audio_interface Dec 16 09:48:24 volumio volumio[2821]: info: Scanning category miscellanea Dec 16 09:48:24 volumio volumio[2821]: info: Scanning category music_service Dec 16 09:48:24 volumio volumio[2821]: info: Scanning category plugins.json Dec 16 09:48:24 volumio volumio[2821]: info: Scanning category system_controller Dec 16 09:48:24 volumio volumio[2821]: info: Scanning category user_interface Dec 16 09:48:24 volumio volumio[2821]: info: Scanning into folder /data/plugins/ Dec 16 09:48:24 volumio volumio[2821]: info: Scanning category music_service Dec 16 09:48:24 volumio volumio[2821]: info: Plugin folders cleanup completed Dec 16 09:48:24 volumio volumio[2821]: info: ------------------------------------------- Dec 16 09:48:24 volumio volumio[2821]: info: ----- Core plugins startup ---- Dec 16 09:48:24 volumio volumio[2821]: info: ------------------------------------------- Dec 16 09:48:24 volumio volumio[2821]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 09:48:24 volumio volumio[2821]: info: Adding plugin upnp to MyMusic Plugins Dec 16 09:48:24 volumio volumio[2821]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 09:48:24 volumio volumio[2821]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 09:48:24 volumio volumio[2821]: info: Loading plugins from folder /data/plugins/ Dec 16 09:48:24 volumio volumio[2821]: info: Loading plugin "system"... Dec 16 09:48:24 volumio dhcpcd[760]: wlan0: using IPv4LL address 169.254.106.75 Dec 16 09:48:24 volumio dhcpcd[760]: wlan0: adding route to 169.254.0.0/16 Dec 16 09:48:24 volumio dhcpcd[760]: wlan0: adding default route Dec 16 09:48:24 volumio avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.106.75. Dec 16 09:48:24 volumio avahi-daemon[631]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 09:48:24 volumio avahi-daemon[631]: Registering new address record for 169.254.106.75 on wlan0.IPv4. Dec 16 09:48:24 volumio volumio[2821]: info: Loading plugin "appearance"... Dec 16 09:48:25 volumio ntpd[1047]: Listen normally on 4 wlan0 169.254.106.75:123 Dec 16 09:48:25 volumio ntpd[1047]: new interface(s) found: waking up resolver Dec 16 09:48:25 volumio volumio[2821]: info: Loading plugin "network"... Dec 16 09:48:25 volumio volumio[2821]: info: Refreshing Cached IP Addresses Dec 16 09:48:25 volumio sudo[2860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 09:48:25 volumio sudo[2860]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:25 volumio sudo[2860]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:25 volumio sudo[2862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 09:48:25 volumio sudo[2862]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:25 volumio sudo[2862]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:25 volumio volumio[2821]: info: Loading plugin "services"... Dec 16 09:48:25 volumio volumio[2821]: info: Loading plugin "alsa_controller"... Dec 16 09:48:25 volumio sudo[2871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 09:48:25 volumio sudo[2871]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:26 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "wizard"... Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "networkfs"... Dec 16 09:48:26 volumio volumio[2821]: info: Starting Udev Watcher for removable devices Dec 16 09:48:26 volumio volumio[2821]: info: Ignoring mount for partition: boot Dec 16 09:48:26 volumio volumio[2821]: info: Ignoring mount for partition: volumio Dec 16 09:48:26 volumio volumio[2821]: info: Ignoring mount for partition: volumio_data Dec 16 09:48:26 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "volumio_command_line_client"... Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "upnp"... Dec 16 09:48:26 volumio volumio[2821]: info: [1765856906142] Starting Upmpd Daemon Dec 16 09:48:26 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "my_music"... Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "mpd"... Dec 16 09:48:26 volumio volumio[2821]: info: Plugin upnp_browser is not enabled Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "alarm-clock"... Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "airplay_emulation"... Dec 16 09:48:26 volumio volumio[2821]: info: Starting Shairport Sync Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "last_100"... Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "webradio"... Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "i2s_dacs"... Dec 16 09:48:26 volumio volumio[2821]: info: I2S DAC not set, start Auto-detection Dec 16 09:48:26 volumio volumio[2821]: info: Loading plugin "volumiodiscovery"... Dec 16 09:48:27 volumio volumio[2821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:27 volumio volumio[2821]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:27 volumio node[2821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:27 volumio volumio[2821]: *** WARNING *** For more information see Dec 16 09:48:27 volumio volumio[2821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:27 volumio volumio[2821]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:27 volumio volumio[2821]: *** WARNING *** For more information see Dec 16 09:48:27 volumio node[2821]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:27 volumio node[2821]: *** WARNING *** For more information see Dec 16 09:48:27 volumio node[2821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 09:48:27 volumio node[2821]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 09:48:27 volumio node[2821]: *** WARNING *** For more information see Dec 16 09:48:27 volumio volumio[2821]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 09:48:27 volumio volumio[2821]: info: Discovery: Started advertising with name: Volumio Dec 16 09:48:27 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 09:48:27 volumio volumio[2821]: info: Loading plugin "squeezelite_mc"... Dec 16 09:48:27 volumio dhcpcd[760]: wlan0: leased 192.168.3.89 for 86400 seconds Dec 16 09:48:27 volumio avahi-daemon[631]: Registering new address record for 192.168.3.89 on wlan0.IPv4. Dec 16 09:48:28 volumio dhcpcd[760]: wlan0: adding route to 192.168.3.0/24 Dec 16 09:48:28 volumio dhcpcd[760]: wlan0: changing default route via 192.168.3.1 Dec 16 09:48:28 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:48:28 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:48:28 volumio volumio[2821]: info: Loading plugin "outputs"... Dec 16 09:48:28 volumio volumio[2821]: info: Loading plugin "albumart"... Dec 16 09:48:28 volumio volumio[2821]: info: Plugin example_plugin is not enabled Dec 16 09:48:28 volumio volumio[2821]: info: Loading plugin "inputs"... Dec 16 09:48:28 volumio volumio[2821]: info: Loading plugin "updater_comm"... Dec 16 09:48:28 volumio ntpd[1047]: ntpd exiting on signal 15 (Terminated) Dec 16 09:48:28 volumio ntpd[1047]: 87.103.245.205 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 195.122.253.38 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 93.191.12.44 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 92.118.113.65 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 193.192.36.3 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio systemd[1]: Stopping Network Time Service... Dec 16 09:48:28 volumio ntpd[1047]: 89.109.251.22 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 162.159.200.1 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 217.170.87.229 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 46.188.16.150 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 90.188.9.144 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio systemd[1]: ntp.service: Succeeded. Dec 16 09:48:28 volumio ntpd[1047]: 45.141.102.99 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 212.113.99.6 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 91.206.16.3 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio ntpd[1047]: 83.243.68.157 local addr 169.254.106.75 -> Dec 16 09:48:28 volumio systemd[1]: Stopped Network Time Service. Dec 16 09:48:28 volumio systemd[1]: Starting Network Time Service... Dec 16 09:48:28 volumio ntpd[2929]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 16 09:48:28 volumio ntpd[2929]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 16 09:48:28 volumio systemd[1]: Started Network Time Service. Dec 16 09:48:28 volumio ntpd[2936]: proto: precision = 1.746 usec (-19) Dec 16 09:48:28 volumio dhcpcd[760]: wlan0: deleting route to 169.254.0.0/16 Dec 16 09:48:28 volumio avahi-daemon[631]: Withdrawing address record for 169.254.106.75 on wlan0. Dec 16 09:48:28 volumio avahi-daemon[631]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.106.75. Dec 16 09:48:28 volumio avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.89. Dec 16 09:48:28 volumio ntpd[2936]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 16 09:48:28 volumio ntpd[2936]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 16 09:48:28 volumio ntpd[2936]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1085 days ago Dec 16 09:48:28 volumio ntpd[2936]: Listen and drop on 0 v6wildcard [::]:123 Dec 16 09:48:28 volumio ntpd[2936]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 16 09:48:28 volumio ntpd[2936]: Listen normally on 2 lo 127.0.0.1:123 Dec 16 09:48:28 volumio ntpd[2936]: Listen normally on 3 wlan0 192.168.3.89:123 Dec 16 09:48:28 volumio ntpd[2936]: Listening on routing socket on fd #20 for interface updates Dec 16 09:48:28 volumio ntpd[2936]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Dec 16 09:48:28 volumio ntpd[2936]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Dec 16 09:48:28 volumio volumio[2821]: info: Plugin mpdemulation is not enabled Dec 16 09:48:28 volumio volumio[2821]: info: Loading plugin "rest_api"... Dec 16 09:48:28 volumio volumio[2821]: info: Loading plugin "websocket"... Dec 16 09:48:28 volumio volumio[2821]: info: Starting Socket.io Server version 2.3.0 Dec 16 09:48:28 volumio volumio[2821]: info: Loading plugin "RoonBridge"... Dec 16 09:48:28 volumio volumio[2821]: Forking 1 albumart workers Dec 16 09:48:29 volumio volumio[2821]: info: Applying required configuration parameters for plugin RoonBridge Dec 16 09:48:29 volumio volumio[2821]: info: Loading plugin "motherearthradio"... Dec 16 09:48:29 volumio sudo[2871]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:29 volumio volumio[2821]: info: Applying required configuration parameters for plugin motherearthradio Dec 16 09:48:29 volumio volumio[2821]: info: [1765856909838] [MotherEarth] API delay: 5 Dec 16 09:48:29 volumio volumio[2821]: info: Loading plugin "radio_paradise"... Dec 16 09:48:30 volumio volumio[2821]: info: Applying required configuration parameters for plugin radio_paradise Dec 16 09:48:30 volumio volumio[2821]: info: [1765856910186] [RadioParadise] API delay: 5 Dec 16 09:48:30 volumio volumio[2821]: Starting albumart workers Dec 16 09:48:30 volumio volumio[2821]: info: Loading i18n strings for locale ru Dec 16 09:48:30 volumio volumio[2821]: Updating browse sources language Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::initPlayerControls Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 09:48:30 volumio volumio[2821]: Express server listening on port 3000 Dec 16 09:48:30 volumio volumio[2821]: [Metrics] WebUI: 8s 19.17ms Dec 16 09:48:30 volumio volumio[2821]: info: Setting Device type: x86 Dec 16 09:48:30 volumio volumio[2821]: info: CoreStateMachine::resetVolumioState Dec 16 09:48:30 volumio volumio[2821]: info: CoreStateMachine::getcurrentVolume Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 09:48:30 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:31 volumio volumio[2821]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 16 09:48:31 volumio volumio[2821]: info: Completed loading Core Plugins Dec 16 09:48:31 volumio volumio[2821]: info: Preparing to generate the ALSA configuration file Dec 16 09:48:31 volumio volumio[2821]: info: Volumio Network Manager: Network status updated: 2 Dec 16 09:48:31 volumio volumio[2821]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 16 09:48:32 volumio volumio[2821]: info: VolumeController:: Volume=74 Mute =false Dec 16 09:48:32 volumio volumio[2821]: info: CoreStateMachine::pushState Dec 16 09:48:32 volumio volumio[2821]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:32 volumio volumio[2821]: info: CoreStateMachine::updateTrackBlock Dec 16 09:48:32 volumio volumio[2821]: info: CorePlayQueue::getTrackBlock Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 09:48:32 volumio volumio[2821]: info: Reloading queue from file Dec 16 09:48:32 volumio volumio[2821]: info: Asound.conf file unchanged, so no further update is needed Dec 16 09:48:32 volumio volumio[2821]: info: Output device has changed, restarting MPD Dec 16 09:48:32 volumio volumio[2821]: info: Output device has changed, restarting Shairport Sync Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:32 volumio sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 09:48:32 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:32 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:32 volumio sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 09:48:32 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:32 volumio systemd[1]: Stopping Music Player Daemon... Dec 16 09:48:32 volumio volumio[2821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 09:48:32 volumio volumio[2821]: info: ___________ START PLUGINS ___________ Dec 16 09:48:32 volumio systemd[1]: mpd.service: Succeeded. Dec 16 09:48:32 volumio systemd[1]: Stopped Music Player Daemon. Dec 16 09:48:32 volumio volumio[2821]: info: ControllerMpd::onStart: Initializing MPD Dec 16 09:48:32 volumio volumio[2821]: info: Creating MPD Configuration file Dec 16 09:48:32 volumio systemd[1]: Starting Music Player Daemon... Dec 16 09:48:32 volumio sudo[2988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 09:48:32 volumio sudo[2988]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:32 volumio sudo[2988]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:32 volumio sudo[2992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 09:48:32 volumio sudo[2992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:32 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 09:48:32 volumio systemd[1]: mpd.service: Succeeded. Dec 16 09:48:32 volumio systemd[1]: Stopped Music Player Daemon. Dec 16 09:48:32 volumio volumio[2821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:32 volumio systemd[1]: Starting Music Player Daemon... Dec 16 09:48:32 volumio volumio[2821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:32 volumio volumio[2821]: info: [1765856912738] CoreMusicLibrary::Adding element Last_100 Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:32 volumio volumio[2821]: info: [1765856912747] CoreMusicLibrary::Adding element Webradio Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 09:48:32 volumio volumio[2821]: info: Initializing BBC Radios Dec 16 09:48:32 volumio sudo[2997]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 09:48:32 volumio sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:32 volumio sudo[2997]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 09:48:32 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:32 volumio volumio[2821]: info: [squeezelite_mc] Starting proxy server... Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:33 volumio volumio[2821]: info: [1765856913004] CoreMusicLibrary::Adding element Mother Earth Radio Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:33 volumio volumio[2821]: Cannot find translation for source Mother Earth Radio Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 09:48:33 volumio volumio[2821]: info: [1765856913031] CoreMusicLibrary::Adding element Radio Paradise Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 09:48:33 volumio volumio[2821]: Cannot find translation for source Mother Earth Radio Dec 16 09:48:33 volumio volumio[2821]: Cannot find translation for source Radio Paradise Dec 16 09:48:33 volumio volumio[2821]: info: Volumio Calling Home Dec 16 09:48:33 volumio sudo[3014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Dec 16 09:48:33 volumio sudo[3014]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:33 volumio sudo[3014]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:33 volumio volumio[2821]: info: [squeezelite_mc] Proxy server started on port 43183 Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 09:48:33 volumio volumio[2821]: info: CoreStateMachine::setRepeat null single undefined Dec 16 09:48:33 volumio volumio[2821]: info: CoreStateMachine::pushState Dec 16 09:48:33 volumio volumio[2821]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:33 volumio volumio[2821]: info: CoreStateMachine::setRandom null Dec 16 09:48:33 volumio volumio[2821]: info: CoreStateMachine::pushState Dec 16 09:48:33 volumio volumio[2821]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:33 volumio volumio[2821]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:34 volumio volumio[2821]: info: VolumeController:: Volume=74 Mute =false Dec 16 09:48:34 volumio volumio[2821]: info: CoreStateMachine::pushState Dec 16 09:48:34 volumio volumio[2821]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:34 volumio volumio[2821]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:34 volumio ntpd[2936]: Soliciting pool server 62.113.114.46 Dec 16 09:48:34 volumio volumio[2821]: info: MPD Permissions set Dec 16 09:48:34 volumio volumio[2821]: info: MPD Permissions set Dec 16 09:48:34 volumio ntpd[2936]: Soliciting pool server 90.188.9.144 Dec 16 09:48:34 volumio ntpd[2936]: Soliciting pool server 185.224.212.113 Dec 16 09:48:34 volumio ntpd[2936]: Soliciting pool server 51.250.53.172 Dec 16 09:48:35 volumio mpd[3012]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 09:48:35 volumio mpd[3012]: output: No 'audio_output' defined in config file Dec 16 09:48:35 volumio mpd[3012]: output: Successfully detected a sndio audio device Dec 16 09:48:35 volumio mpd[3012]: zeroconf: No global port, disabling zeroconf Dec 16 09:48:35 volumio systemd[1]: Started Music Player Daemon. Dec 16 09:48:35 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:35 volumio sudo[2992]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:35 volumio volumio[2821]: info: VolumeController:: Volume=74 Mute =false Dec 16 09:48:35 volumio volumio[2821]: info: CoreStateMachine::pushState Dec 16 09:48:35 volumio volumio[2821]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:35 volumio volumio[2821]: info: CoreCommandRouter::volumioPushState Dec 16 09:48:35 volumio ntpd[2936]: Soliciting pool server 92.255.126.2 Dec 16 09:48:35 volumio ntpd[2936]: Soliciting pool server 92.255.126.12 Dec 16 09:48:35 volumio volumio[2821]: info: [squeezelite_mc] Server discovery started Dec 16 09:48:35 volumio volumio[2821]: info: [squeezelite_mc] Player finder started Dec 16 09:48:35 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:35 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:35 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:35 volumio volumio[2821]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 09:48:35 volumio volumio[2821]: info: Volumio called home Dec 16 09:48:35 volumio volumio[2821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 09:48:36 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:48:36 volumio volumio[2821]: error: MPD error: The expression evaluated to a falsy value: Dec 16 09:48:36 volumio volumio[2821]: assert.ok(self.idling) Dec 16 09:48:36 volumio volumio[2821]: error: The expression evaluated to a falsy value: Dec 16 09:48:36 volumio volumio[2821]: assert.ok(self.idling) Dec 16 09:48:36 volumio volumio[2821]: info: MPD running with PID3012 Dec 16 09:48:36 volumio volumio[2821]: ,establishing connection Dec 16 09:48:36 volumio volumio[2821]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE","S24_3LE"] Dec 16 09:48:36 volumio volumio[2821]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"5","mixerType":"Hardware","mixer":"PCM"} Dec 16 09:48:36 volumio volumio[2821]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 16 09:48:37 volumio volumio[2821]: error: updateQueue error: null Dec 16 09:48:37 volumio sudo[3060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 09:48:37 volumio sudo[3060]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio sudo[3062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 09:48:37 volumio sudo[3062]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio volumio[2821]: info: Starting Shairport Sync Dec 16 09:48:37 volumio sudo[3058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 16 09:48:37 volumio sudo[3060]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio sudo[3058]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio sudo[3062]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio sudo[3058]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio volumio[2821]: info: Starting Shairport Sync Dec 16 09:48:37 volumio volumio[2821]: info: Starting Shairport Sync Dec 16 09:48:37 volumio sudo[3067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 09:48:37 volumio sudo[3067]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio volumio[2821]: error: updateQueue error: null Dec 16 09:48:37 volumio sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 09:48:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 09:48:37 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 16 09:48:37 volumio sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 09:48:37 volumio volumio[2821]: info: CoreCommandRouter::volumioGetState Dec 16 09:48:37 volumio volumio[2821]: info: CorePlayQueue::getTrack 0 Dec 16 09:48:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 09:48:37 volumio sudo[3067]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio sudo[3072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 09:48:37 volumio sudo[3072]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 09:48:37 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 16 09:48:37 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 16 09:48:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 09:48:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 09:48:37 volumio sudo[3069]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 09:48:37 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 16 09:48:37 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 16 09:48:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 09:48:37 volumio volumio[2821]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Dec 16 09:48:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 09:48:37 volumio sudo[3072]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio volumio[2821]: info: Shairport-Sync Started Dec 16 09:48:37 volumio sudo[3080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Dec 16 09:48:37 volumio volumio[2821]: Error adding Membership: Error: addMembership EINVAL Dec 16 09:48:37 volumio volumio[2821]: info: Shairport-Sync Started Dec 16 09:48:37 volumio sudo[3080]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio volumio[2821]: info: Shairport-Sync Started Dec 16 09:48:37 volumio sudo[3080]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio volumio[2821]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 16 09:48:37 volumio sudo[3085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 09:48:37 volumio sudo[3085]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio sudo[3085]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio volumio[2821]: info: Upmpdcli Daemon Started Dec 16 09:48:37 volumio sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 16 09:48:37 volumio sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio sudo[3089]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:37 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Dec 16 09:48:37 volumio sudo[3092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 09:48:37 volumio sudo[3092]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:37 volumio systemd[1]: Reloading. Dec 16 09:48:38 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. Dec 16 09:48:38 volumio volumio-remote-updater[620]: [2025-12-16 09:48:38] [connect] Successful connection Dec 16 09:48:38 volumio volumio-remote-updater[620]: [2025-12-16 09:48:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765856918 101 Dec 16 09:48:38 volumio volumio[2821]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Dec 16 09:48:39 volumio sudo[3092]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:39 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 09:48:39 volumio sudo[3108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 09:48:39 volumio sudo[3108]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:39 volumio sudo[3108]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:39 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Dec 16 09:48:39 volumio sudo[3111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Dec 16 09:48:39 volumio sudo[3111]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:39 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Dec 16 09:48:39 volumio systemd[1]: squeezelite.service: Succeeded. Dec 16 09:48:39 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Dec 16 09:48:39 volumio sudo[3111]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:39 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 09:48:39 volumio sudo[3115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 09:48:39 volumio sudo[3115]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:40 volumio sudo[3115]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:40 volumio volumio[2821]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Dec 16 09:48:40 volumio sudo[3119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Dec 16 09:48:40 volumio sudo[3119]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:40 volumio sudo[3119]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:40 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Dec 16 09:48:40 volumio sudo[3122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Dec 16 09:48:40 volumio sudo[3122]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:40 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 16 09:48:40 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Dec 16 09:48:40 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Dec 16 09:48:40 volumio sudo[3122]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:40 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 09:48:40 volumio sudo[3129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 09:48:40 volumio sudo[3129]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:40 volumio sudo[3129]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:41 volumio volumio[2821]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Dec 16 09:48:41 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 09:48:41 volumio sudo[3133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 09:48:41 volumio sudo[3133]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:41 volumio sudo[3133]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:41 volumio mpd[3012]: exception: No database Dec 16 09:48:41 volumio mpd[3012]: exception: No database Dec 16 09:48:41 volumio volumio[2821]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Dec 16 09:48:41 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 09:48:41 volumio sudo[3139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 09:48:41 volumio sudo[3139]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:41 volumio sudo[3139]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:42 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 09:48:42 volumio sudo[3142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 09:48:42 volumio sudo[3142]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:42 volumio sudo[3142]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:43 volumio volumio[2821]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 09:48:43 volumio sudo[3145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 09:48:43 volumio sudo[3145]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:43 volumio sudo[3145]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:43 volumio volumio[2821]: info: Completed starting Core Plugins Dec 16 09:48:43 volumio volumio[2821]: info: ------------------------------------------- Dec 16 09:48:43 volumio volumio[2821]: info: ----- MyVolumio plugins startup ---- Dec 16 09:48:43 volumio volumio[2821]: info: ------------------------------------------- Dec 16 09:48:43 volumio volumio[2821]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 09:48:49 volumio sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 09:48:49 volumio sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:49 volumio sudo[3162]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:49 volumio sudo[3164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 09:48:49 volumio sudo[3164]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:48:49 volumio sudo[3164]: pam_unix(sudo:session): session closed for user root Dec 16 09:48:49 volumio volumio[2821]: verbose: New Socket.io Connection to 192.168.3.89 from 192.168.3.82 UA: Mozilla/5.0 (Linux; arm_64; Android 7.0; BLN-L21) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.5993.609 YaApp_Android/23.114.1 YaSearchBrowser/23.114.1 BroPP/1.0 SA/3 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 16 09:48:51 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 16 09:48:51 volumio dhcpcd[760]: wlan0: carrier lost Dec 16 09:48:51 volumio avahi-daemon[631]: Withdrawing address record for 192.168.3.89 on wlan0. Dec 16 09:48:51 volumio avahi-daemon[631]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.89. Dec 16 09:48:51 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:c0:92:22:a9:40 reason=4 locally_generated=1 Dec 16 09:48:51 volumio avahi-daemon[631]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 09:48:51 volumio volumio[2821]: info: Discovery: A device disappeared from network Dec 16 09:48:51 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 16 09:48:51 volumio volumio[2821]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Dec 16 09:48:51 volumio dhcpcd[760]: wlan0: deleting route to 192.168.3.0/24 Dec 16 09:48:51 volumio dhcpcd[760]: wlan0: deleting default route via 192.168.3.1 Dec 16 09:48:51 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:48:51 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:48:52 volumio wpa_supplicant[892]: wlan0: SME: Trying to authenticate with a8:c0:92:22:a9:40 (SSID='HUAWEI-F1CY67' freq=2462 MHz) Dec 16 09:48:52 volumio kernel: wlan0: authenticate with a8:c0:92:22:a9:40 Dec 16 09:48:52 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Dec 16 09:48:52 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 1/3) Dec 16 09:48:53 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 2/3) Dec 16 09:48:53 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 3/3) Dec 16 09:48:53 volumio kernel: wlan0: authentication with a8:c0:92:22:a9:40 timed out Dec 16 09:48:54 volumio ntpd[2936]: Deleting interface #3 wlan0, 192.168.3.89#123, interface stats: received=2, sent=8, dropped=0, active_time=26 secs Dec 16 09:48:54 volumio ntpd[2936]: 51.250.53.172 local addr 192.168.3.89 -> Dec 16 09:48:54 volumio ntpd[2936]: 62.113.114.46 local addr 192.168.3.89 -> Dec 16 09:48:56 volumio volumio[2821]: info: Volumio Network Manager: Network status updated: 0 Dec 16 09:49:00 volumio wpa_supplicant[892]: wlan0: SME: Trying to authenticate with a8:c0:92:22:a9:40 (SSID='HUAWEI-F1CY67' freq=2462 MHz) Dec 16 09:49:00 volumio kernel: wlan0: authenticate with a8:c0:92:22:a9:40 Dec 16 09:49:00 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Dec 16 09:49:00 volumio kernel: wlan0: send auth to a8:c0:92:22:a9:40 (try 1/3) Dec 16 09:49:00 volumio wpa_supplicant[892]: wlan0: Trying to associate with a8:c0:92:22:a9:40 (SSID='HUAWEI-F1CY67' freq=2462 MHz) Dec 16 09:49:00 volumio kernel: wlan0: authenticated Dec 16 09:49:00 volumio kernel: wlan0: associate with a8:c0:92:22:a9:40 (try 1/3) Dec 16 09:49:00 volumio wpa_supplicant[892]: wlan0: Associated with a8:c0:92:22:a9:40 Dec 16 09:49:00 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 09:49:00 volumio kernel: wlan0: RX AssocResp from a8:c0:92:22:a9:40 (capab=0x1431 status=0 aid=3) Dec 16 09:49:00 volumio kernel: wlan0: associated Dec 16 09:49:01 volumio wpa_supplicant[892]: wlan0: WPA: Key negotiation completed with a8:c0:92:22:a9:40 [PTK=CCMP GTK=CCMP] Dec 16 09:49:01 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:c0:92:22:a9:40 completed [id=0 id_str=] Dec 16 09:49:01 volumio dhcpcd[760]: wlan0: carrier acquired Dec 16 09:49:01 volumio dhcpcd[760]: wlan0: IAID d6:c1:59:0a Dec 16 09:49:01 volumio dhcpcd[760]: wlan0: rebinding lease of 192.168.3.89 Dec 16 09:49:01 volumio dhcpcd[760]: wlan0: probing address 192.168.3.89/24 Dec 16 09:49:01 volumio dhcpcd[760]: wlan0: soliciting an IPv6 router Dec 16 09:49:02 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 09:49:02 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:49:02 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 09:49:03 volumio volumio[2821]: error: MyVolumio Plugin failed to start in a timely fashion Dec 16 09:49:03 volumio volumio[2821]: [Metrics] CommandRouter: 39s 842.59ms Dec 16 09:49:03 volumio volumio[2821]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 09:49:03 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 09:49:03 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 09:49:03 volumio volumio[2821]: info: CoreCommandRouter::Close All Modals sent Dec 16 09:49:03 volumio volumio[2821]: info: CoreCommandRouter::Close All Modals sent Dec 16 09:49:04 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 09:49:04 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 09:49:04 volumio volumio[2821]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 09:49:05 volumio volumio[2821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 09:49:05 volumio volumio[2821]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 09:49:05 volumio volumio[2821]: at doSend (dgram.js:714:16) Dec 16 09:49:05 volumio volumio[2821]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 16 09:49:05 volumio volumio[2821]: at afterDns (dgram.js:660:5) Dec 16 09:49:05 volumio volumio[2821]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 16 09:49:05 volumio volumio[2821]: errno: -101, Dec 16 09:49:05 volumio volumio[2821]: code: 'ENETUNREACH', Dec 16 09:49:05 volumio volumio[2821]: syscall: 'send', Dec 16 09:49:05 volumio volumio[2821]: address: '255.255.255.255', Dec 16 09:49:05 volumio volumio[2821]: port: 3483 Dec 16 09:49:05 volumio volumio[2821]: } Dec 16 09:49:05 volumio volumio[2821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 09:49:05 volumio dhcpcd[760]: wlan0: leased 192.168.3.89 for 86400 seconds Dec 16 09:49:05 volumio dhcpcd[760]: wlan0: adding route to 192.168.3.0/24 Dec 16 09:49:05 volumio dhcpcd[760]: wlan0: adding default route via 192.168.3.1 Dec 16 09:49:05 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:49:05 volumio dhcpcd[760]: wlan0: dhcp_envoption 213: Operation not supported Dec 16 09:49:05 volumio avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.89. Dec 16 09:49:05 volumio avahi-daemon[631]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 09:49:05 volumio avahi-daemon[631]: Registering new address record for 192.168.3.89 on wlan0.IPv4. Dec 16 09:49:06 volumio ntpd[2936]: ntpd exiting on signal 15 (Terminated) Dec 16 09:49:06 volumio systemd[1]: Stopping Network Time Service... Dec 16 09:49:06 volumio systemd[1]: ntp.service: Succeeded. Dec 16 09:49:06 volumio systemd[1]: Stopped Network Time Service. Dec 16 09:49:06 volumio sudo[3313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 09:48 Dec 16 09:49:06 volumio sudo[3313]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 09:49:06 volumio systemd[1]: Starting Network Time Service... Dec 16 09:49:06 volumio ntpd[3316]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 16 09:49:06 volumio ntpd[3316]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 16 09:49:06 volumio systemd[1]: Started Network Time Service. Dec 16 09:49:06 volumio ntpd[3323]: proto: precision = 2.025 usec (-19) Dec 16 09:49:06 volumio ntpd[3323]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 16 09:49:06 volumio ntpd[3323]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 16 09:49:06 volumio ntpd[3323]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1085 days ago Dec 16 09:49:06 volumio ntpd[3323]: Listen and drop on 0 v6wildcard [::]:123 Dec 16 09:49:06 volumio ntpd[3323]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 16 09:49:06 volumio ntpd[3323]: Listen normally on 2 lo 127.0.0.1:123 Dec 16 09:49:06 volumio ntpd[3323]: Listen normally on 3 wlan0 192.168.3.89:123 Dec 16 09:49:06 volumio ntpd[3323]: Listening on routing socket on fd #20 for interface updates Dec 16 09:49:06 volumio ntpd[3323]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 09:49:06 volumio ntpd[3323]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"