-- Logs begin at Sun 2025-12-21 12:16:56 CET, end at Sun 2025-12-21 13:15:24 CET. -- Dec 21 13:14:00 wellenflieger ntpd[9519]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 21 13:14:03 wellenflieger volumio[10053]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 21 13:14:06 wellenflieger wpa_supplicant[1199]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 13:14:07 wellenflieger wpa_supplicant[1199]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 13:14:14 wellenflieger ntpd[9519]: 185.232.69.65 local addr 169.254.17.226 -> Dec 21 13:14:15 wellenflieger ntpd[9519]: 81.169.233.252 local addr 169.254.17.226 -> Dec 21 13:14:18 wellenflieger wpa_supplicant[1199]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 13:14:19 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:14:21 wellenflieger ntpd[9519]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 21 13:14:21 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:14:21 wellenflieger dhcpcd[944]: wlan0: carrier lost Dec 21 13:14:21 wellenflieger avahi-daemon[816]: Withdrawing address record for 169.254.17.226 on wlan0. Dec 21 13:14:21 wellenflieger avahi-daemon[816]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.17.226. Dec 21 13:14:21 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 1 Dec 21 13:14:21 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-DISCONNECTED bssid=68:d7:9a:59:36:4b reason=4 locally_generated=1 Dec 21 13:14:21 wellenflieger avahi-daemon[816]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 21 13:14:21 wellenflieger dhcpcd[944]: wlan0: deleting route to 169.254.0.0/16 Dec 21 13:14:21 wellenflieger dhcpcd[944]: wlan0: deleting default route Dec 21 13:14:21 wellenflieger volumio[10053]: info: Discovery: A device disappeared from network Dec 21 13:14:21 wellenflieger volumio[10053]: info: Discovery: Device wellenflieger disappeared from network Dec 21 13:14:21 wellenflieger volumio[10053]: info: Discovery: A device disappeared from network Dec 21 13:14:22 wellenflieger ntpd[9519]: Deleting interface #4 wlan0, 169.254.17.226#123, interface stats: received=0, sent=86, dropped=2, active_time=481 secs Dec 21 13:14:22 wellenflieger ntpd[9519]: 141.144.230.32 local addr 169.254.17.226 -> Dec 21 13:14:22 wellenflieger ntpd[9519]: 158.101.188.125 local addr 169.254.17.226 -> Dec 21 13:14:22 wellenflieger ntpd[9519]: 188.68.34.173 local addr 169.254.17.226 -> Dec 21 13:14:22 wellenflieger ntpd[9519]: 131.188.3.222 local addr 169.254.17.226 -> Dec 21 13:14:22 wellenflieger ntpd[9519]: 85.214.133.14 local addr 169.254.17.226 -> Dec 21 13:14:22 wellenflieger ntpd[9519]: 212.132.97.26 local addr 169.254.17.226 -> Dec 21 13:14:22 wellenflieger volumio[10053]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:14:22 wellenflieger volumio[10053]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 13:14:22 wellenflieger volumio[10053]: at doSend (dgram.js:714:16) Dec 21 13:14:22 wellenflieger volumio[10053]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 21 13:14:22 wellenflieger volumio[10053]: at afterDns (dgram.js:660:5) Dec 21 13:14:22 wellenflieger volumio[10053]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 21 13:14:22 wellenflieger volumio[10053]: errno: -101, Dec 21 13:14:22 wellenflieger volumio[10053]: code: 'ENETUNREACH', Dec 21 13:14:22 wellenflieger volumio[10053]: syscall: 'send', Dec 21 13:14:22 wellenflieger volumio[10053]: address: '255.255.255.255', Dec 21 13:14:22 wellenflieger volumio[10053]: port: 3483 Dec 21 13:14:22 wellenflieger volumio[10053]: } Dec 21 13:14:22 wellenflieger volumio[10053]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:14:23 wellenflieger sudo[11419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 13:13 Dec 21 13:14:23 wellenflieger sudo[11419]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:23 wellenflieger sudo[11419]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:23 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 21 13:14:23 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 21 13:14:23 wellenflieger systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 21 13:14:23 wellenflieger systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 21 13:14:23 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:14:23 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:14:23 wellenflieger systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 21 13:14:23 wellenflieger systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Dec 21 13:14:23 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:14:23 wellenflieger systemd[1]: Stopped Volumio Backend Module. Dec 21 13:14:23 wellenflieger systemd[1]: Started Volumio Backend Module. Dec 21 13:14:23 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:14:24 wellenflieger volumio[11433]: info: ------------------------------------------- Dec 21 13:14:24 wellenflieger volumio[11433]: info: ----- Volumio3 ---- Dec 21 13:14:24 wellenflieger volumio[11433]: info: ------------------------------------------- Dec 21 13:14:24 wellenflieger volumio[11433]: info: ----- System startup ---- Dec 21 13:14:24 wellenflieger volumio[11433]: info: ------------------------------------------- Dec 21 13:14:24 wellenflieger volumio[11433]: info: MYVOLUMIO Environment detected Dec 21 13:14:24 wellenflieger volumio[11433]: info: Plugin folders cleanup Dec 21 13:14:24 wellenflieger volumio[11433]: info: Scanning into folder /volumio/app/plugins/ Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning category audio_interface Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning category miscellanea Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning category music_service Dec 21 13:14:25 wellenflieger wpa_supplicant[1199]: wlan0: SME: Trying to authenticate with 18:e8:29:58:2a:e4 (SSID='FZL_MM' freq=5805 MHz) Dec 21 13:14:25 wellenflieger kernel: wlan0: authenticate with 18:e8:29:58:2a:e4 Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning category plugins.json Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning category system_controller Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning category user_interface Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning into folder /data/plugins/ Dec 21 13:14:25 wellenflieger volumio[11433]: info: Scanning category music_service Dec 21 13:14:25 wellenflieger volumio[11433]: info: Plugin folders cleanup completed Dec 21 13:14:25 wellenflieger volumio[11433]: info: ------------------------------------------- Dec 21 13:14:25 wellenflieger volumio[11433]: info: ----- Core plugins startup ---- Dec 21 13:14:25 wellenflieger volumio[11433]: info: ------------------------------------------- Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 13:14:25 wellenflieger volumio[11433]: info: Adding plugin upnp to MyMusic Plugins Dec 21 13:14:25 wellenflieger volumio[11433]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 13:14:25 wellenflieger volumio[11433]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugins from folder /data/plugins/ Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "system"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "appearance"... Dec 21 13:14:25 wellenflieger wpa_supplicant[1199]: wlan0: Trying to associate with 18:e8:29:58:2a:e4 (SSID='FZL_MM' freq=5805 MHz) Dec 21 13:14:25 wellenflieger kernel: wlan0: send auth to 18:e8:29:58:2a:e4 (try 1/3) Dec 21 13:14:25 wellenflieger kernel: wlan0: authenticated Dec 21 13:14:25 wellenflieger kernel: wlan0: associate with 18:e8:29:58:2a:e4 (try 1/3) Dec 21 13:14:25 wellenflieger kernel: wlan0: associate with 18:e8:29:58:2a:e4 (try 2/3) Dec 21 13:14:25 wellenflieger kernel: wlan0: RX AssocResp from 18:e8:29:58:2a:e4 (capab=0x1511 status=0 aid=1) Dec 21 13:14:25 wellenflieger kernel: wlan0: associated Dec 21 13:14:25 wellenflieger wpa_supplicant[1199]: wlan0: Associated with 18:e8:29:58:2a:e4 Dec 21 13:14:25 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "network"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: Refreshing Cached IP Addresses Dec 21 13:14:25 wellenflieger sudo[11463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 13:14:25 wellenflieger sudo[11463]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:25 wellenflieger sudo[11463]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:25 wellenflieger sudo[11465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 13:14:25 wellenflieger sudo[11465]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:25 wellenflieger sudo[11465]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "services"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "alsa_controller"... Dec 21 13:14:25 wellenflieger sudo[11478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 13:14:25 wellenflieger sudo[11478]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:25 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "wizard"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "networkfs"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: Starting Udev Watcher for removable devices Dec 21 13:14:25 wellenflieger volumio[11433]: info: Ignoring mount for partition: boot Dec 21 13:14:25 wellenflieger volumio[11433]: info: Ignoring mount for partition: volumio Dec 21 13:14:25 wellenflieger volumio[11433]: info: Ignoring mount for partition: volumio_data Dec 21 13:14:25 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "volumio_command_line_client"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "upnp"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: [1766319265962] Starting Upmpd Daemon Dec 21 13:14:25 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "my_music"... Dec 21 13:14:25 wellenflieger volumio[11433]: info: Loading plugin "mpd"... Dec 21 13:14:26 wellenflieger ntpd[9519]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 21 13:14:26 wellenflieger ntpd[9519]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 21 13:14:26 wellenflieger ntpd[9519]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 21 13:14:26 wellenflieger ntpd[9519]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "upnp_browser"... Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "alarm-clock"... Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "airplay_emulation"... Dec 21 13:14:26 wellenflieger volumio[11433]: info: Starting Shairport Sync Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "last_100"... Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "webradio"... Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "i2s_dacs"... Dec 21 13:14:26 wellenflieger volumio[11433]: info: I2S DAC not set, start Auto-detection Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "volumiodiscovery"... Dec 21 13:14:26 wellenflieger volumio[11433]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:26 wellenflieger volumio[11433]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:26 wellenflieger volumio[11433]: *** WARNING *** For more information see Dec 21 13:14:26 wellenflieger volumio[11433]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:26 wellenflieger volumio[11433]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:26 wellenflieger volumio[11433]: *** WARNING *** For more information see Dec 21 13:14:26 wellenflieger node[11433]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:26 wellenflieger node[11433]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:26 wellenflieger node[11433]: *** WARNING *** For more information see Dec 21 13:14:26 wellenflieger node[11433]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:26 wellenflieger node[11433]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:26 wellenflieger node[11433]: *** WARNING *** For more information see Dec 21 13:14:26 wellenflieger volumio[11433]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 13:14:26 wellenflieger wpa_supplicant[1199]: wlan0: WPA: Key negotiation completed with 18:e8:29:58:2a:e4 [PTK=CCMP GTK=CCMP] Dec 21 13:14:26 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:e8:29:58:2a:e4 completed [id=0 id_str=] Dec 21 13:14:26 wellenflieger dhcpcd[944]: wlan0: carrier acquired Dec 21 13:14:26 wellenflieger volumio[11433]: info: Discovery: Started advertising with name: Wellenflieger Dec 21 13:14:26 wellenflieger wpa_supplicant[1199]: RRM: Unexpected neighbor report Dec 21 13:14:26 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:26 wellenflieger dhcpcd[944]: wlan0: IAID 6b:e4:fa:f6 Dec 21 13:14:26 wellenflieger volumio[11433]: info: Loading plugin "squeezelite_mc"... Dec 21 13:14:26 wellenflieger dhcpcd[944]: wlan0: soliciting a DHCP lease Dec 21 13:14:27 wellenflieger volumio[11433]: info: Loading plugin "outputs"... Dec 21 13:14:27 wellenflieger volumio[11433]: info: Loading plugin "albumart"... Dec 21 13:14:27 wellenflieger dhcpcd[944]: wlan0: soliciting an IPv6 router Dec 21 13:14:27 wellenflieger volumio[11433]: info: Plugin example_plugin is not enabled Dec 21 13:14:27 wellenflieger volumio[11433]: info: Loading plugin "inputs"... Dec 21 13:14:27 wellenflieger volumio[11433]: info: Loading plugin "updater_comm"... Dec 21 13:14:27 wellenflieger volumio[11433]: Forking 1 albumart workers Dec 21 13:14:27 wellenflieger volumio[11433]: info: Plugin mpdemulation is not enabled Dec 21 13:14:27 wellenflieger volumio[11433]: info: Loading plugin "rest_api"... Dec 21 13:14:27 wellenflieger volumio[11433]: info: Loading plugin "websocket"... Dec 21 13:14:27 wellenflieger volumio[11433]: info: Starting Socket.io Server version 2.3.0 Dec 21 13:14:27 wellenflieger volumio[11433]: info: Loading i18n strings for locale de Dec 21 13:14:27 wellenflieger volumio[11433]: Updating browse sources language Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::initPlayerControls Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: Express server listening on port 3000 Dec 21 13:14:27 wellenflieger volumio[11433]: [Metrics] WebUI: 3s 424.32ms Dec 21 13:14:27 wellenflieger volumio[11433]: info: Setting Device type: x86 Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreStateMachine::resetVolumioState Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreStateMachine::getcurrentVolume Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 21 13:14:27 wellenflieger volumio[11433]: info: Completed loading Core Plugins Dec 21 13:14:27 wellenflieger volumio[11433]: info: Preparing to generate the ALSA configuration file Dec 21 13:14:27 wellenflieger volumio[11433]: info: Cannot read play queue from file Dec 21 13:14:27 wellenflieger volumio[11433]: info: Volumio Network Manager: Network status updated: 0 Dec 21 13:14:27 wellenflieger volumio[11433]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 21 13:14:27 wellenflieger volumio[11433]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 21 13:14:27 wellenflieger volumio[11433]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 21 13:14:27 wellenflieger volumio[11433]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreStateMachine::pushState Dec 21 13:14:27 wellenflieger volumio[11433]: Starting albumart workers Dec 21 13:14:27 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreStateMachine::updateTrackBlock Dec 21 13:14:27 wellenflieger volumio[11433]: info: CorePlayQueue::getTrackBlock Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:27 wellenflieger volumio[11433]: info: Asound.conf file unchanged, so no further update is needed Dec 21 13:14:27 wellenflieger volumio[11433]: info: Output device has changed, restarting MPD Dec 21 13:14:27 wellenflieger sudo[11516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:14:27 wellenflieger volumio[11433]: info: Output device has changed, restarting Shairport Sync Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:27 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:27 wellenflieger sudo[11516]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:27 wellenflieger sudo[11516]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:27 wellenflieger sudo[11518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:14:27 wellenflieger sudo[11518]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:27 wellenflieger systemd[1]: Stopping Music Player Daemon... Dec 21 13:14:27 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:14:27 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:14:28 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:14:28 wellenflieger volumio[11433]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:28 wellenflieger volumio[11433]: info: ___________ START PLUGINS ___________ Dec 21 13:14:28 wellenflieger volumio[11433]: info: ControllerMpd::onStart: Initializing MPD Dec 21 13:14:28 wellenflieger volumio[11433]: info: Creating MPD Configuration file Dec 21 13:14:28 wellenflieger sudo[11524]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:14:28 wellenflieger sudo[11524]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:28 wellenflieger sudo[11524]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:28 wellenflieger volumio[11433]: info: [1766319268098] CoreMusicLibrary::Adding element Medienserver Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:28 wellenflieger sudo[11526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:14:28 wellenflieger sudo[11526]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:28 wellenflieger sudo[11526]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:28 wellenflieger sudo[11530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:14:28 wellenflieger sudo[11530]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:28 wellenflieger systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 21 13:14:28 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:28 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:14:28 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:14:28 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Dec 21 13:14:28 wellenflieger volumio[11433]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:28 wellenflieger sudo[11533]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:14:28 wellenflieger sudo[11533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:28 wellenflieger sudo[11533]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:28 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:28] [connect] Successful connection Dec 21 13:14:28 wellenflieger volumio[11433]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:28 wellenflieger volumio[11433]: info: [1766319268316] CoreMusicLibrary::Adding element Last_100 Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:28 wellenflieger volumio[11433]: info: [1766319268320] CoreMusicLibrary::Adding element Webradio Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:28 wellenflieger volumio[11433]: info: Initializing BBC Radios Dec 21 13:14:28 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:28 wellenflieger volumio[11433]: info: [squeezelite_mc] Starting proxy server... Dec 21 13:14:28 wellenflieger volumio[11433]: info: Volumio Calling Home Dec 21 13:14:28 wellenflieger volumio[11433]: info: [squeezelite_mc] Proxy server started on port 46155 Dec 21 13:14:28 wellenflieger ntpd[9519]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreStateMachine::setRepeat null single undefined Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreStateMachine::pushState Dec 21 13:14:28 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreStateMachine::setRandom null Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreStateMachine::pushState Dec 21 13:14:28 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:28 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 13:14:28 wellenflieger volumio[11433]: Unhandled rejection Error: No sockets available, cannot start. Dec 21 13:14:28 wellenflieger volumio[11433]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 21 13:14:28 wellenflieger volumio[11433]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 21 13:14:28 wellenflieger volumio[11433]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 21 13:14:28 wellenflieger volumio[11433]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 21 13:14:28 wellenflieger volumio[11433]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 21 13:14:28 wellenflieger volumio[11433]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 21 13:14:28 wellenflieger volumio[11433]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 21 13:14:28 wellenflieger volumio[11433]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 21 13:14:28 wellenflieger volumio[11433]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 21 13:14:28 wellenflieger volumio[11433]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 21 13:14:28 wellenflieger volumio[11433]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 21 13:14:28 wellenflieger volumio[11433]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 21 13:14:28 wellenflieger volumio[11433]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 21 13:14:28 wellenflieger volumio[11433]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 21 13:14:28 wellenflieger volumio[11433]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 21 13:14:28 wellenflieger volumio[11433]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:29 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:29 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 13:14:29 wellenflieger volumio[11433]: info: Received Get System Info Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 13:14:29 wellenflieger volumio[11433]: info: Discovery: Getting this device information Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:29 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:29 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:29 wellenflieger volumio[11433]: info: Listing playlists Dec 21 13:14:29 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766319268 101 Dec 21 13:14:29 wellenflieger volumio[11433]: 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: 3 Dec 21 13:14:29 wellenflieger volumio[11433]: info: MPD Permissions set Dec 21 13:14:29 wellenflieger mpd[11541]: Dec 21 13:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 13:14:29 wellenflieger systemd[1]: Started Music Player Daemon. Dec 21 13:14:29 wellenflieger sudo[11530]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:29 wellenflieger sudo[11518]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:29 wellenflieger volumio[11433]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreStateMachine::pushState Dec 21 13:14:29 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:29 wellenflieger volumio[11433]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 21 13:14:29 wellenflieger volumio[11433]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreStateMachine::pushState Dec 21 13:14:29 wellenflieger volumio[11433]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:29 wellenflieger volumio[11433]: info: [squeezelite_mc] Server discovery started Dec 21 13:14:29 wellenflieger volumio[11433]: info: [squeezelite_mc] Player finder started Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:29 wellenflieger volumio[11433]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:29 wellenflieger volumio[11433]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 21 13:14:29 wellenflieger volumio[11433]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:14:29 wellenflieger volumio[11433]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 13:14:29 wellenflieger volumio[11433]: at doSend (dgram.js:714:16) Dec 21 13:14:29 wellenflieger volumio[11433]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 21 13:14:29 wellenflieger volumio[11433]: at afterDns (dgram.js:660:5) Dec 21 13:14:29 wellenflieger volumio[11433]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 21 13:14:29 wellenflieger volumio[11433]: errno: -101, Dec 21 13:14:29 wellenflieger volumio[11433]: code: 'ENETUNREACH', Dec 21 13:14:29 wellenflieger volumio[11433]: syscall: 'send', Dec 21 13:14:29 wellenflieger volumio[11433]: address: '255.255.255.255', Dec 21 13:14:29 wellenflieger volumio[11433]: port: 3483 Dec 21 13:14:29 wellenflieger volumio[11433]: } Dec 21 13:14:29 wellenflieger volumio[11433]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:14:29 wellenflieger sudo[11582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 13:13 Dec 21 13:14:29 wellenflieger sudo[11582]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:29 wellenflieger sudo[11582]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:29 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 21 13:14:29 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 21 13:14:29 wellenflieger systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 21 13:14:29 wellenflieger sudo[11478]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:29 wellenflieger systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 21 13:14:29 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:14:29 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:14:29 wellenflieger systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 21 13:14:29 wellenflieger systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Dec 21 13:14:29 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:14:29 wellenflieger systemd[1]: Stopped Volumio Backend Module. Dec 21 13:14:29 wellenflieger systemd[1]: Started Volumio Backend Module. Dec 21 13:14:29 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:14:30 wellenflieger volumio[11596]: info: ------------------------------------------- Dec 21 13:14:30 wellenflieger volumio[11596]: info: ----- Volumio3 ---- Dec 21 13:14:30 wellenflieger volumio[11596]: info: ------------------------------------------- Dec 21 13:14:30 wellenflieger volumio[11596]: info: ----- System startup ---- Dec 21 13:14:30 wellenflieger volumio[11596]: info: ------------------------------------------- Dec 21 13:14:31 wellenflieger volumio[11596]: info: MYVOLUMIO Environment detected Dec 21 13:14:31 wellenflieger volumio[11596]: info: Plugin folders cleanup Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning into folder /volumio/app/plugins/ Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning category audio_interface Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning category miscellanea Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning category music_service Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning category plugins.json Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning category system_controller Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning category user_interface Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning into folder /data/plugins/ Dec 21 13:14:31 wellenflieger volumio[11596]: info: Scanning category music_service Dec 21 13:14:31 wellenflieger volumio[11596]: info: Plugin folders cleanup completed Dec 21 13:14:31 wellenflieger volumio[11596]: info: ------------------------------------------- Dec 21 13:14:31 wellenflieger volumio[11596]: info: ----- Core plugins startup ---- Dec 21 13:14:31 wellenflieger volumio[11596]: info: ------------------------------------------- Dec 21 13:14:31 wellenflieger volumio[11596]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 13:14:31 wellenflieger volumio[11596]: info: Adding plugin upnp to MyMusic Plugins Dec 21 13:14:31 wellenflieger volumio[11596]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 13:14:31 wellenflieger volumio[11596]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 13:14:31 wellenflieger volumio[11596]: info: Loading plugins from folder /data/plugins/ Dec 21 13:14:31 wellenflieger volumio[11596]: info: Loading plugin "system"... Dec 21 13:14:31 wellenflieger volumio[11596]: info: Loading plugin "appearance"... Dec 21 13:14:31 wellenflieger dhcpcd[944]: wlan0: probing for an IPv4LL address Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "network"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Refreshing Cached IP Addresses Dec 21 13:14:32 wellenflieger sudo[11622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 13:14:32 wellenflieger sudo[11622]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:32 wellenflieger sudo[11624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 13:14:32 wellenflieger sudo[11624]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:32 wellenflieger sudo[11622]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:32 wellenflieger sudo[11624]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "services"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "alsa_controller"... Dec 21 13:14:32 wellenflieger sudo[11633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 13:14:32 wellenflieger sudo[11633]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:32 wellenflieger sudo[11633]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:32 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "wizard"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "networkfs"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Starting Udev Watcher for removable devices Dec 21 13:14:32 wellenflieger volumio[11596]: info: Ignoring mount for partition: boot Dec 21 13:14:32 wellenflieger volumio[11596]: info: Ignoring mount for partition: volumio Dec 21 13:14:32 wellenflieger volumio[11596]: info: Ignoring mount for partition: volumio_data Dec 21 13:14:32 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "volumio_command_line_client"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "upnp"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: [1766319272286] Starting Upmpd Daemon Dec 21 13:14:32 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "my_music"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "mpd"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "upnp_browser"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "alarm-clock"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "airplay_emulation"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Starting Shairport Sync Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "last_100"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "webradio"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "i2s_dacs"... Dec 21 13:14:32 wellenflieger volumio[11596]: info: I2S DAC not set, start Auto-detection Dec 21 13:14:32 wellenflieger volumio[11596]: info: Loading plugin "volumiodiscovery"... Dec 21 13:14:32 wellenflieger volumio[11596]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:32 wellenflieger volumio[11596]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:32 wellenflieger volumio[11596]: *** WARNING *** For more information see Dec 21 13:14:32 wellenflieger volumio[11596]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:32 wellenflieger volumio[11596]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:32 wellenflieger volumio[11596]: *** WARNING *** For more information see Dec 21 13:14:32 wellenflieger node[11596]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:32 wellenflieger node[11596]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:32 wellenflieger volumio[11596]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 13:14:32 wellenflieger node[11596]: *** WARNING *** For more information see Dec 21 13:14:32 wellenflieger node[11596]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:32 wellenflieger node[11596]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:32 wellenflieger node[11596]: *** WARNING *** For more information see Dec 21 13:14:32 wellenflieger volumio[11596]: info: Discovery: Started advertising with name: Wellenflieger Dec 21 13:14:33 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading plugin "squeezelite_mc"... Dec 21 13:14:33 wellenflieger wpa_supplicant[1199]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading plugin "outputs"... Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading plugin "albumart"... Dec 21 13:14:33 wellenflieger volumio[11596]: info: Plugin example_plugin is not enabled Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading plugin "inputs"... Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading plugin "updater_comm"... Dec 21 13:14:33 wellenflieger volumio[11596]: info: Plugin mpdemulation is not enabled Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading plugin "rest_api"... Dec 21 13:14:33 wellenflieger volumio[11596]: Forking 1 albumart workers Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading plugin "websocket"... Dec 21 13:14:33 wellenflieger volumio[11596]: info: Starting Socket.io Server version 2.3.0 Dec 21 13:14:33 wellenflieger volumio[11596]: info: Loading i18n strings for locale de Dec 21 13:14:33 wellenflieger volumio[11596]: Updating browse sources language Dec 21 13:14:33 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:33 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:33 wellenflieger volumio[11596]: info: CoreCommandRouter::initPlayerControls Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: Express server listening on port 3000 Dec 21 13:14:34 wellenflieger volumio[11596]: [Metrics] WebUI: 3s 590.41ms Dec 21 13:14:34 wellenflieger volumio[11596]: info: Setting Device type: x86 Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreStateMachine::resetVolumioState Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreStateMachine::getcurrentVolume Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 21 13:14:34 wellenflieger volumio[11596]: info: Completed loading Core Plugins Dec 21 13:14:34 wellenflieger volumio[11596]: info: Preparing to generate the ALSA configuration file Dec 21 13:14:34 wellenflieger volumio[11596]: info: Cannot read play queue from file Dec 21 13:14:34 wellenflieger volumio[11596]: info: Volumio Network Manager: Network status updated: 0 Dec 21 13:14:34 wellenflieger volumio[11596]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 21 13:14:34 wellenflieger volumio[11596]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 21 13:14:34 wellenflieger volumio[11596]: info: Cannot use regular scanning, forcing with ap-force Dec 21 13:14:34 wellenflieger sudo[11668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 21 13:14:34 wellenflieger sudo[11668]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:34 wellenflieger sudo[11668]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:34 wellenflieger volumio[11596]: command failed: Device or resource busy (-16) Dec 21 13:14:34 wellenflieger volumio[11596]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 21 13:14:34 wellenflieger volumio[11596]: command failed: Device or resource busy (-16) Dec 21 13:14:34 wellenflieger volumio[11596]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 21 13:14:34 wellenflieger volumio[11596]: Starting albumart workers Dec 21 13:14:34 wellenflieger volumio[11596]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 21 13:14:34 wellenflieger volumio[11596]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreStateMachine::pushState Dec 21 13:14:34 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreStateMachine::updateTrackBlock Dec 21 13:14:34 wellenflieger volumio[11596]: info: CorePlayQueue::getTrackBlock Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:34 wellenflieger volumio[11596]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 21 13:14:34 wellenflieger volumio[11596]: info: Asound.conf file unchanged, so no further update is needed Dec 21 13:14:34 wellenflieger volumio[11596]: info: Output device has changed, restarting MPD Dec 21 13:14:34 wellenflieger sudo[11676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:14:34 wellenflieger sudo[11676]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:34 wellenflieger sudo[11676]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:34 wellenflieger volumio[11596]: info: Output device has changed, restarting Shairport Sync Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:34 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:34] [connect] Successful connection Dec 21 13:14:34 wellenflieger sudo[11679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:14:34 wellenflieger sudo[11679]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:34 wellenflieger systemd[1]: Stopping Music Player Daemon... Dec 21 13:14:34 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:14:34 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:14:34 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:14:34 wellenflieger volumio[11596]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:34 wellenflieger volumio[11596]: info: ___________ START PLUGINS ___________ Dec 21 13:14:34 wellenflieger volumio[11596]: info: ControllerMpd::onStart: Initializing MPD Dec 21 13:14:34 wellenflieger volumio[11596]: info: Creating MPD Configuration file Dec 21 13:14:34 wellenflieger sudo[11684]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:14:34 wellenflieger sudo[11684]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:34 wellenflieger sudo[11684]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:34 wellenflieger sudo[11687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:14:34 wellenflieger sudo[11687]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:34 wellenflieger sudo[11687]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:34 wellenflieger volumio[11596]: info: [1766319274918] CoreMusicLibrary::Adding element Medienserver Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:34 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:34 wellenflieger sudo[11691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:14:34 wellenflieger sudo[11691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:35 wellenflieger systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 21 13:14:35 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:14:35 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:14:35 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:14:35 wellenflieger volumio[11596]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:35 wellenflieger sudo[11696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:14:35 wellenflieger sudo[11696]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:35 wellenflieger sudo[11696]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:35 wellenflieger volumio[11596]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:35 wellenflieger volumio[11596]: info: [1766319275141] CoreMusicLibrary::Adding element Last_100 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:35 wellenflieger volumio[11596]: info: [1766319275145] CoreMusicLibrary::Adding element Webradio Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:35 wellenflieger volumio[11596]: info: Initializing BBC Radios Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:35 wellenflieger volumio[11596]: info: [squeezelite_mc] Starting proxy server... Dec 21 13:14:35 wellenflieger volumio[11596]: info: Volumio Calling Home Dec 21 13:14:35 wellenflieger volumio[11596]: info: [squeezelite_mc] Proxy server started on port 40083 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreStateMachine::setRepeat null single undefined Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreStateMachine::pushState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreStateMachine::setRandom null Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreStateMachine::pushState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:35 wellenflieger volumio[11596]: Unhandled rejection Error: No sockets available, cannot start. Dec 21 13:14:35 wellenflieger volumio[11596]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 21 13:14:35 wellenflieger volumio[11596]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 21 13:14:35 wellenflieger volumio[11596]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 21 13:14:35 wellenflieger volumio[11596]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 21 13:14:35 wellenflieger volumio[11596]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 21 13:14:35 wellenflieger volumio[11596]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 21 13:14:35 wellenflieger volumio[11596]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 21 13:14:35 wellenflieger volumio[11596]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 21 13:14:35 wellenflieger volumio[11596]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 21 13:14:35 wellenflieger volumio[11596]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 21 13:14:35 wellenflieger volumio[11596]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 21 13:14:35 wellenflieger volumio[11596]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 21 13:14:35 wellenflieger volumio[11596]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 21 13:14:35 wellenflieger volumio[11596]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 21 13:14:35 wellenflieger volumio[11596]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 21 13:14:35 wellenflieger volumio[11596]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 13:14:35 wellenflieger kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 18:e8:29:58:2a:e4 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 13:14:35 wellenflieger volumio[11596]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreStateMachine::pushState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:35 wellenflieger volumio[11596]: info: MPD Permissions set Dec 21 13:14:35 wellenflieger volumio[11596]: info: MPD Permissions set Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 13:14:35 wellenflieger volumio[11596]: info: Received Get System Info Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 13:14:35 wellenflieger volumio[11596]: info: Discovery: Getting this device information Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: Listing playlists Dec 21 13:14:35 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766319274 101 Dec 21 13:14:35 wellenflieger volumio[11596]: 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: 3 Dec 21 13:14:35 wellenflieger volumio[11596]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 21 13:14:35 wellenflieger volumio[11596]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreStateMachine::pushState Dec 21 13:14:35 wellenflieger volumio[11596]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:35 wellenflieger volumio[11596]: info: [squeezelite_mc] Server discovery started Dec 21 13:14:35 wellenflieger volumio[11596]: info: [squeezelite_mc] Player finder started Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:35 wellenflieger volumio[11596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:35 wellenflieger volumio[11596]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 21 13:14:35 wellenflieger volumio[11596]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:14:35 wellenflieger volumio[11596]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 13:14:35 wellenflieger volumio[11596]: at doSend (dgram.js:714:16) Dec 21 13:14:35 wellenflieger volumio[11596]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 21 13:14:35 wellenflieger volumio[11596]: at afterDns (dgram.js:660:5) Dec 21 13:14:35 wellenflieger volumio[11596]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 21 13:14:35 wellenflieger volumio[11596]: errno: -101, Dec 21 13:14:35 wellenflieger volumio[11596]: code: 'ENETUNREACH', Dec 21 13:14:35 wellenflieger volumio[11596]: syscall: 'send', Dec 21 13:14:35 wellenflieger volumio[11596]: address: '255.255.255.255', Dec 21 13:14:35 wellenflieger volumio[11596]: port: 3483 Dec 21 13:14:35 wellenflieger volumio[11596]: } Dec 21 13:14:35 wellenflieger volumio[11596]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:14:36 wellenflieger mpd[11709]: Dec 21 13:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 13:14:36 wellenflieger systemd[1]: Started Music Player Daemon. Dec 21 13:14:36 wellenflieger sudo[11679]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:36 wellenflieger sudo[11691]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:36 wellenflieger sudo[11739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 13:13 Dec 21 13:14:36 wellenflieger sudo[11739]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:36 wellenflieger sudo[11739]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:36 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 21 13:14:36 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 21 13:14:36 wellenflieger systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 21 13:14:36 wellenflieger systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 21 13:14:36 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:14:36 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:14:36 wellenflieger systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 21 13:14:36 wellenflieger systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Dec 21 13:14:36 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:14:36 wellenflieger systemd[1]: Stopped Volumio Backend Module. Dec 21 13:14:36 wellenflieger systemd[1]: Started Volumio Backend Module. Dec 21 13:14:36 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:14:36 wellenflieger ntpd[9519]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 21 13:14:37 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:14:37 wellenflieger dhcpcd[944]: wlan0: using IPv4LL address 169.254.17.226 Dec 21 13:14:37 wellenflieger avahi-daemon[816]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.17.226. Dec 21 13:14:37 wellenflieger avahi-daemon[816]: New relevant interface wlan0.IPv4 for mDNS. Dec 21 13:14:37 wellenflieger dhcpcd[944]: wlan0: adding route to 169.254.0.0/16 Dec 21 13:14:37 wellenflieger avahi-daemon[816]: Registering new address record for 169.254.17.226 on wlan0.IPv4. Dec 21 13:14:37 wellenflieger dhcpcd[944]: wlan0: adding default route Dec 21 13:14:37 wellenflieger wpa_supplicant[1199]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 13:14:37 wellenflieger volumio[11753]: info: ------------------------------------------- Dec 21 13:14:37 wellenflieger volumio[11753]: info: ----- Volumio3 ---- Dec 21 13:14:37 wellenflieger volumio[11753]: info: ------------------------------------------- Dec 21 13:14:37 wellenflieger volumio[11753]: info: ----- System startup ---- Dec 21 13:14:37 wellenflieger volumio[11753]: info: ------------------------------------------- Dec 21 13:14:38 wellenflieger volumio[11753]: info: MYVOLUMIO Environment detected Dec 21 13:14:38 wellenflieger volumio[11753]: info: Plugin folders cleanup Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning into folder /volumio/app/plugins/ Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning category audio_interface Dec 21 13:14:38 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning category miscellanea Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning category music_service Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning category plugins.json Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning category system_controller Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning category user_interface Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning into folder /data/plugins/ Dec 21 13:14:38 wellenflieger volumio[11753]: info: Scanning category music_service Dec 21 13:14:38 wellenflieger volumio[11753]: info: Plugin folders cleanup completed Dec 21 13:14:38 wellenflieger volumio[11753]: info: ------------------------------------------- Dec 21 13:14:38 wellenflieger volumio[11753]: info: ----- Core plugins startup ---- Dec 21 13:14:38 wellenflieger volumio[11753]: info: ------------------------------------------- Dec 21 13:14:38 wellenflieger volumio[11753]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 13:14:38 wellenflieger volumio[11753]: info: Adding plugin upnp to MyMusic Plugins Dec 21 13:14:38 wellenflieger volumio[11753]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 13:14:38 wellenflieger volumio[11753]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 13:14:38 wellenflieger volumio[11753]: info: Loading plugins from folder /data/plugins/ Dec 21 13:14:38 wellenflieger volumio[11753]: info: Loading plugin "system"... Dec 21 13:14:38 wellenflieger volumio[11753]: info: Loading plugin "appearance"... Dec 21 13:14:38 wellenflieger volumio[11753]: info: Loading plugin "network"... Dec 21 13:14:38 wellenflieger ntpd[9519]: Listen normally on 5 wlan0 169.254.17.226:123 Dec 21 13:14:38 wellenflieger ntpd[9519]: new interface(s) found: waking up resolver Dec 21 13:14:38 wellenflieger volumio[11753]: info: Refreshing Cached IP Addresses Dec 21 13:14:38 wellenflieger sudo[11789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 13:14:38 wellenflieger sudo[11789]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:38 wellenflieger sudo[11789]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:38 wellenflieger sudo[11791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 13:14:38 wellenflieger sudo[11791]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:38 wellenflieger sudo[11791]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:38 wellenflieger volumio[11753]: info: Loading plugin "services"... Dec 21 13:14:38 wellenflieger volumio[11753]: info: Loading plugin "alsa_controller"... Dec 21 13:14:38 wellenflieger sudo[11804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 13:14:38 wellenflieger sudo[11804]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:39 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "wizard"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "networkfs"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Starting Udev Watcher for removable devices Dec 21 13:14:39 wellenflieger volumio[11753]: info: Ignoring mount for partition: boot Dec 21 13:14:39 wellenflieger volumio[11753]: info: Ignoring mount for partition: volumio Dec 21 13:14:39 wellenflieger volumio[11753]: info: Ignoring mount for partition: volumio_data Dec 21 13:14:39 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "volumio_command_line_client"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "upnp"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: [1766319279058] Starting Upmpd Daemon Dec 21 13:14:39 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "my_music"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "mpd"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "upnp_browser"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "alarm-clock"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "airplay_emulation"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Starting Shairport Sync Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "last_100"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "webradio"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "i2s_dacs"... Dec 21 13:14:39 wellenflieger volumio[11753]: info: I2S DAC not set, start Auto-detection Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "volumiodiscovery"... Dec 21 13:14:39 wellenflieger volumio[11753]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:39 wellenflieger volumio[11753]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:39 wellenflieger volumio[11753]: *** WARNING *** For more information see Dec 21 13:14:39 wellenflieger volumio[11753]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:39 wellenflieger volumio[11753]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:39 wellenflieger volumio[11753]: *** WARNING *** For more information see Dec 21 13:14:39 wellenflieger node[11753]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:39 wellenflieger node[11753]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:39 wellenflieger node[11753]: *** WARNING *** For more information see Dec 21 13:14:39 wellenflieger node[11753]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:14:39 wellenflieger node[11753]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:14:39 wellenflieger node[11753]: *** WARNING *** For more information see Dec 21 13:14:39 wellenflieger volumio[11753]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 13:14:39 wellenflieger volumio[11753]: info: Discovery: Started advertising with name: Wellenflieger Dec 21 13:14:39 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:14:39 wellenflieger volumio[11753]: info: Loading plugin "squeezelite_mc"... Dec 21 13:14:39 wellenflieger dhcpcd[944]: wlan0: offered 172.16.158.58 from 172.16.0.1 Dec 21 13:14:39 wellenflieger dhcpcd[944]: wlan0: probing address 172.16.158.58/16 Dec 21 13:14:40 wellenflieger volumio[11753]: info: Loading plugin "outputs"... Dec 21 13:14:40 wellenflieger volumio[11753]: info: Loading plugin "albumart"... Dec 21 13:14:40 wellenflieger volumio[11753]: info: Plugin example_plugin is not enabled Dec 21 13:14:40 wellenflieger volumio[11753]: info: Loading plugin "inputs"... Dec 21 13:14:40 wellenflieger volumio[11753]: info: Loading plugin "updater_comm"... Dec 21 13:14:40 wellenflieger volumio[11753]: Forking 1 albumart workers Dec 21 13:14:40 wellenflieger volumio[11753]: info: Plugin mpdemulation is not enabled Dec 21 13:14:40 wellenflieger volumio[11753]: info: Loading plugin "rest_api"... Dec 21 13:14:40 wellenflieger volumio[11753]: info: Loading plugin "websocket"... Dec 21 13:14:40 wellenflieger volumio[11753]: info: Starting Socket.io Server version 2.3.0 Dec 21 13:14:40 wellenflieger volumio[11753]: info: Loading i18n strings for locale de Dec 21 13:14:40 wellenflieger volumio[11753]: Updating browse sources language Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::initPlayerControls Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:40 wellenflieger volumio[11753]: Express server listening on port 3000 Dec 21 13:14:40 wellenflieger volumio[11753]: [Metrics] WebUI: 3s 742.59ms Dec 21 13:14:40 wellenflieger volumio[11753]: info: Setting Device type: x86 Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreStateMachine::resetVolumioState Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreStateMachine::getcurrentVolume Dec 21 13:14:40 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:41 wellenflieger volumio[11753]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 21 13:14:41 wellenflieger volumio[11753]: info: Completed loading Core Plugins Dec 21 13:14:41 wellenflieger volumio[11753]: info: Preparing to generate the ALSA configuration file Dec 21 13:14:41 wellenflieger volumio[11753]: Starting albumart workers Dec 21 13:14:41 wellenflieger volumio[11753]: info: Cannot read play queue from file Dec 21 13:14:41 wellenflieger volumio[11753]: info: Volumio Network Manager: Network status updated: 2 Dec 21 13:14:41 wellenflieger volumio[11753]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 21 13:14:41 wellenflieger volumio[11753]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 21 13:14:41 wellenflieger volumio[11753]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreStateMachine::pushState Dec 21 13:14:41 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreStateMachine::updateTrackBlock Dec 21 13:14:41 wellenflieger volumio[11753]: info: CorePlayQueue::getTrackBlock Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:41 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:41] [connect] Successful connection Dec 21 13:14:41 wellenflieger volumio[11753]: info: Asound.conf file unchanged, so no further update is needed Dec 21 13:14:41 wellenflieger volumio[11753]: info: Output device has changed, restarting MPD Dec 21 13:14:41 wellenflieger sudo[11847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:14:41 wellenflieger sudo[11847]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:41 wellenflieger volumio[11753]: info: Output device has changed, restarting Shairport Sync Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:41 wellenflieger sudo[11847]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:41 wellenflieger sudo[11849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:14:41 wellenflieger sudo[11849]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:41 wellenflieger systemd[1]: Stopping Music Player Daemon... Dec 21 13:14:41 wellenflieger volumio[11753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:41 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:14:41 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:14:41 wellenflieger volumio[11753]: info: ___________ START PLUGINS ___________ Dec 21 13:14:41 wellenflieger volumio[11753]: info: ControllerMpd::onStart: Initializing MPD Dec 21 13:14:41 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:14:41 wellenflieger volumio[11753]: info: Creating MPD Configuration file Dec 21 13:14:41 wellenflieger sudo[11857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:14:41 wellenflieger sudo[11857]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:41 wellenflieger sudo[11855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:14:41 wellenflieger sudo[11855]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:41 wellenflieger sudo[11855]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:41 wellenflieger sudo[11857]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:41 wellenflieger volumio[11753]: info: [1766319281650] CoreMusicLibrary::Adding element Medienserver Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:41 wellenflieger sudo[11861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:14:41 wellenflieger sudo[11861]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:41 wellenflieger systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 21 13:14:41 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:14:41 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:14:41 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:14:41 wellenflieger sudo[11870]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:14:41 wellenflieger sudo[11870]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:41 wellenflieger sudo[11870]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:41 wellenflieger volumio[11753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:41 wellenflieger volumio[11753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:41 wellenflieger volumio[11753]: info: [1766319281908] CoreMusicLibrary::Adding element Last_100 Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:14:41 wellenflieger volumio[11753]: info: [1766319281915] CoreMusicLibrary::Adding element Webradio Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:41 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:41 wellenflieger volumio[11753]: info: Initializing BBC Radios Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: [squeezelite_mc] Starting proxy server... Dec 21 13:14:42 wellenflieger volumio[11753]: info: Volumio Calling Home Dec 21 13:14:42 wellenflieger volumio[11753]: info: [squeezelite_mc] Proxy server started on port 35093 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreStateMachine::setRepeat null single undefined Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreStateMachine::pushState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreStateMachine::setRandom null Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreStateMachine::pushState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:42 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:14:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766319281 101 Dec 21 13:14:42 wellenflieger volumio[11753]: 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 21 13:14:42 wellenflieger volumio[11753]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 21 13:14:42 wellenflieger volumio[11753]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreStateMachine::pushState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:42 wellenflieger volumio[11753]: info: MPD Permissions set Dec 21 13:14:42 wellenflieger volumio[11753]: info: MPD Permissions set Dec 21 13:14:42 wellenflieger volumio[11753]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 21 13:14:42 wellenflieger volumio[11753]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreStateMachine::pushState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioPushState Dec 21 13:14:42 wellenflieger volumio[11753]: info: [squeezelite_mc] Server discovery started Dec 21 13:14:42 wellenflieger volumio[11753]: info: [squeezelite_mc] Player finder started Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 1 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:14:42 wellenflieger mpd[11877]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 13:14:42 wellenflieger mpd[11877]: output: No 'audio_output' defined in config file Dec 21 13:14:42 wellenflieger mpd[11877]: couldn't open play stream: Device or resource busy Dec 21 13:14:42 wellenflieger mpd[11877]: sndio_output: Error opening default sndio device Dec 21 13:14:42 wellenflieger mpd[11877]: alsa_output: Error opening default ALSA device: Device or resource busy Dec 21 13:14:42 wellenflieger mpd[11877]: output: Successfully detected a jack audio device Dec 21 13:14:42 wellenflieger mpd[11877]: zeroconf: No global port, disabling zeroconf Dec 21 13:14:42 wellenflieger systemd[1]: Started Music Player Daemon. Dec 21 13:14:42 wellenflieger sudo[11849]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:42 wellenflieger sudo[11861]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 13:14:42 wellenflieger volumio[11753]: error: [squeezelite_mc] Could not query supported ALSA formats for card 0 because device is busy Dec 21 13:14:42 wellenflieger volumio[11753]: info: Completed starting Core Plugins Dec 21 13:14:42 wellenflieger volumio[11753]: info: ------------------------------------------- Dec 21 13:14:42 wellenflieger volumio[11753]: info: ----- MyVolumio plugins startup ---- Dec 21 13:14:42 wellenflieger volumio[11753]: info: ------------------------------------------- Dec 21 13:14:42 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 13:14:42 wellenflieger volumio[11753]: info: Received Get System Info Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 13:14:42 wellenflieger volumio[11753]: info: Discovery: Getting this device information Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 13:14:42 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:42 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:42 wellenflieger volumio[11753]: info: Listing playlists Dec 21 13:14:42 wellenflieger volumio[11753]: info: Starting Shairport Sync Dec 21 13:14:42 wellenflieger volumio[11753]: info: Starting Shairport Sync Dec 21 13:14:42 wellenflieger sudo[11921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 13:14:42 wellenflieger sudo[11921]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:42 wellenflieger volumio[11753]: info: Starting Shairport Sync Dec 21 13:14:42 wellenflieger systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 13:14:42 wellenflieger systemd[1]: shairport-sync.service: Succeeded. Dec 21 13:14:42 wellenflieger systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 13:14:42 wellenflieger sudo[11924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 13:14:42 wellenflieger sudo[11924]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:43 wellenflieger systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 13:14:43 wellenflieger sudo[11921]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:43 wellenflieger systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 13:14:43 wellenflieger systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 21 13:14:43 wellenflieger systemd[1]: shairport-sync.service: Succeeded. Dec 21 13:14:43 wellenflieger systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 13:14:43 wellenflieger sudo[11928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 13:14:43 wellenflieger sudo[11928]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:43 wellenflieger systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 13:14:43 wellenflieger sudo[11924]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:43 wellenflieger systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 13:14:43 wellenflieger systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 21 13:14:43 wellenflieger systemd[1]: shairport-sync.service: Succeeded. Dec 21 13:14:43 wellenflieger systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 13:14:43 wellenflieger volumio[11753]: error: MPD error: The expression evaluated to a falsy value: Dec 21 13:14:43 wellenflieger volumio[11753]: assert.ok(self.idling) Dec 21 13:14:43 wellenflieger volumio[11753]: error: The expression evaluated to a falsy value: Dec 21 13:14:43 wellenflieger volumio[11753]: assert.ok(self.idling) Dec 21 13:14:43 wellenflieger systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 13:14:43 wellenflieger volumio[11753]: info: MPD running with PID11877 Dec 21 13:14:43 wellenflieger volumio[11753]: ,establishing connection Dec 21 13:14:43 wellenflieger sudo[11928]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:43 wellenflieger volumio[11753]: error: updateQueue error: null Dec 21 13:14:43 wellenflieger volumio[11753]: info: Shairport-Sync Started Dec 21 13:14:43 wellenflieger volumio[11753]: Error adding Membership: Error: addMembership EINVAL Dec 21 13:14:43 wellenflieger volumio[11753]: info: Shairport-Sync Started Dec 21 13:14:43 wellenflieger volumio[11753]: info: Shairport-Sync Started Dec 21 13:14:43 wellenflieger volumio[11753]: error: updateQueue error: null Dec 21 13:14:44 wellenflieger dhcpcd[944]: wlan0: leased 172.16.158.58 for 86400 seconds Dec 21 13:14:44 wellenflieger dhcpcd[944]: wlan0: adding route to 172.16.0.0/16 Dec 21 13:14:44 wellenflieger dhcpcd[944]: wlan0: changing default route via 172.16.0.1 Dec 21 13:14:44 wellenflieger avahi-daemon[816]: Registering new address record for 172.16.158.58 on wlan0.IPv4. Dec 21 13:14:44 wellenflieger volumio[11753]: verbose: New Socket.io Connection to 172.16.158.58:3000 from 172.16.154.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 21 13:14:44 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 21 13:14:44 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:44 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:44 wellenflieger systemd[1]: Stopping Network Time Service... Dec 21 13:14:44 wellenflieger ntpd[9519]: ntpd exiting on signal 15 (Terminated) Dec 21 13:14:44 wellenflieger ntpd[9519]: 141.144.230.32 local addr 169.254.17.226 -> Dec 21 13:14:44 wellenflieger ntpd[9519]: 158.101.188.125 local addr 169.254.17.226 -> Dec 21 13:14:44 wellenflieger ntpd[9519]: 188.68.34.173 local addr 169.254.17.226 -> Dec 21 13:14:44 wellenflieger ntpd[9519]: 131.188.3.222 local addr 169.254.17.226 -> Dec 21 13:14:44 wellenflieger ntpd[9519]: 85.214.133.14 local addr 169.254.17.226 -> Dec 21 13:14:44 wellenflieger ntpd[9519]: 212.132.97.26 local addr 169.254.17.226 -> Dec 21 13:14:44 wellenflieger systemd[1]: ntp.service: Succeeded. Dec 21 13:14:44 wellenflieger systemd[1]: Stopped Network Time Service. Dec 21 13:14:44 wellenflieger systemd[1]: Starting Network Time Service... Dec 21 13:14:44 wellenflieger ntpd[11971]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 21 13:14:44 wellenflieger ntpd[11971]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 21 13:14:44 wellenflieger systemd[1]: Started Network Time Service. Dec 21 13:14:44 wellenflieger ntpd[11977]: proto: precision = 0.084 usec (-23) Dec 21 13:14:44 wellenflieger ntpd[11977]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 21 13:14:44 wellenflieger ntpd[11977]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 21 13:14:44 wellenflieger ntpd[11977]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1090 days ago Dec 21 13:14:44 wellenflieger ntpd[11977]: Listen and drop on 0 v6wildcard [::]:123 Dec 21 13:14:44 wellenflieger ntpd[11977]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 21 13:14:44 wellenflieger ntpd[11977]: Listen normally on 2 lo 127.0.0.1:123 Dec 21 13:14:44 wellenflieger ntpd[11977]: Listen normally on 3 wlan0 169.254.17.226:123 Dec 21 13:14:44 wellenflieger ntpd[11977]: Listen normally on 4 wlan0 172.16.158.58:123 Dec 21 13:14:44 wellenflieger ntpd[11977]: Listening on routing socket on fd #21 for interface updates Dec 21 13:14:44 wellenflieger ntpd[11977]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 21 13:14:44 wellenflieger ntpd[11977]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 21 13:14:44 wellenflieger dhcpcd[944]: wlan0: deleting route to 169.254.0.0/16 Dec 21 13:14:44 wellenflieger avahi-daemon[816]: Withdrawing address record for 169.254.17.226 on wlan0. Dec 21 13:14:44 wellenflieger avahi-daemon[816]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.17.226. Dec 21 13:14:44 wellenflieger avahi-daemon[816]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.158.58. Dec 21 13:14:44 wellenflieger wpa_supplicant[1199]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 13:14:46 wellenflieger ntpd[11977]: Soliciting pool server 176.9.157.155 Dec 21 13:14:46 wellenflieger volumio[11753]: verbose: New Socket.io Connection to 172.16.158.58:3000 from 172.16.200.171 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 21 13:14:46 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 21 13:14:46 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:46 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:46 wellenflieger ntpd[11977]: Soliciting pool server 139.162.152.20 Dec 21 13:14:46 wellenflieger ntpd[11977]: Deleting interface #3 wlan0, 169.254.17.226#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Dec 21 13:14:47 wellenflieger volumio[11753]: verbose: New Socket.io Connection to 172.16.158.58:3000 from 172.16.230.234 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 21 13:14:47 wellenflieger ntpd[11977]: Soliciting pool server 141.95.53.20 Dec 21 13:14:47 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 1 Dec 21 13:14:48 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 1 Dec 21 13:14:48 wellenflieger mpd[11877]: exception: No database Dec 21 13:14:48 wellenflieger mpd[11877]: exception: No database Dec 21 13:14:48 wellenflieger mpd[11877]: exception: No database Dec 21 13:14:48 wellenflieger volumio[11753]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Dec 21 13:14:48 wellenflieger volumio[11753]: info: Volumio called home Dec 21 13:14:48 wellenflieger sudo[11804]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:49 wellenflieger sudo[12026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 13:14:49 wellenflieger sudo[12026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:49 wellenflieger sudo[12026]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:49 wellenflieger sudo[12029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 13:14:49 wellenflieger sudo[12029]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:49 wellenflieger sudo[12029]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:49 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 21 13:14:49 wellenflieger sudo[12032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 21 13:14:49 wellenflieger sudo[12032]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:49 wellenflieger sudo[12032]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:49 wellenflieger volumio[11753]: info: Upmpdcli Daemon Started Dec 21 13:14:49 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:49 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:51 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:14:52 wellenflieger ntpd[11977]: Soliciting pool server 116.203.244.102 Dec 21 13:14:52 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:14:52 wellenflieger ntpd[11977]: Soliciting pool server 81.169.233.252 Dec 21 13:14:52 wellenflieger ntpd[11977]: Soliciting pool server 129.250.35.250 Dec 21 13:14:52 wellenflieger ntpd[11977]: Soliciting pool server 94.130.35.4 Dec 21 13:14:52 wellenflieger ntpd[11977]: Soliciting pool server 188.68.54.217 Dec 21 13:14:52 wellenflieger ntpd[11977]: Soliciting pool server 148.251.5.46 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: adding 532772fc-2b3c-4665-a344-c0c535f9a861 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Found device Wellenflieger Dec 21 13:14:53 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:53 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: adding 461c8fb2-fb2f-4691-a7dd-e10913effb42 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Found device Wild-Amazonas Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Connecting to remote: 172.16.4.9 Dec 21 13:14:53 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:14:53 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: adding 3583de37-559b-4903-8dce-71b4d2735da4 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Found device HorrorBereich Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Connecting to remote: 172.16.230.234 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: adding 6e9d1817-6e43-4c70-ad65-27679433a7da Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Found device Dinozelt Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Connecting to remote: 172.16.200.171 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Connected to remote: 172.16.4.9 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: adding a697263a-261f-464d-969d-5278d37baae6 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Found device eingang Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Connecting to remote: 172.16.55.10 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Connected to remote: 172.16.230.234 Dec 21 13:14:53 wellenflieger wpa_supplicant[1199]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 13:14:53 wellenflieger volumio[11753]: info: Discovery: Connected to remote: 172.16.200.171 Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 21 13:14:54 wellenflieger volumio[11753]: info: Adding plugin bluetooth to MyMusic Plugins Dec 21 13:14:54 wellenflieger volumio[11753]: info: Adding plugin multiroom to MyMusic Plugins Dec 21 13:14:54 wellenflieger volumio[11753]: info: Adding plugin metavolumio to MyMusic Plugins Dec 21 13:14:54 wellenflieger volumio[11753]: info: Adding plugin cd_controller to MyMusic Plugins Dec 21 13:14:54 wellenflieger volumio[11753]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 21 13:14:54 wellenflieger volumio[11753]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 21 13:14:54 wellenflieger volumio[11753]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 21 13:14:54 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 21 13:14:54 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:54 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:14:54 wellenflieger volumio[11753]: info: Starting MyVolumio Remote Streaming Endpoints Dec 21 13:14:55 wellenflieger volumio[11753]: info: MyVolumio not started Dec 21 13:14:55 wellenflieger volumio[11753]: info: Initializing device activation check Dec 21 13:14:55 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 21 13:14:55 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 21 13:14:55 wellenflieger volumio[11753]: info: Starting Streaming Service Transparent Proxy Dec 21 13:14:55 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 21 13:14:55 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 21 13:14:55 wellenflieger volumio[11753]: info: Streaming services startup Dec 21 13:14:55 wellenflieger volumio[11753]: info: Starting Streaming Daemon Dec 21 13:14:55 wellenflieger sudo[12051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 21 13:14:55 wellenflieger sudo[12051]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:14:55 wellenflieger sudo[12051]: pam_unix(sudo:session): session closed for user root Dec 21 13:14:55 wellenflieger volumio[11753]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 21 13:14:55 wellenflieger volumio[11753]: error: Cannot start Volumio Streaming Daemon Dec 21 13:14:55 wellenflieger volumio[11753]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 21 13:14:55 wellenflieger volumio[11753]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 21 13:14:56 wellenflieger volumio[11753]: STREAMING PROXY: Starting server on port 3245 Dec 21 13:14:56 wellenflieger volumio[11753]: Node JS runtime: 14 Dec 21 13:14:57 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:15:06 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:15:06 wellenflieger dhcpcd[944]: wlan0: carrier lost Dec 21 13:15:06 wellenflieger avahi-daemon[816]: Withdrawing address record for 172.16.158.58 on wlan0. Dec 21 13:15:06 wellenflieger avahi-daemon[816]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 172.16.158.58. Dec 21 13:15:06 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 1 Dec 21 13:15:06 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-DISCONNECTED bssid=18:e8:29:58:2a:e4 reason=4 locally_generated=1 Dec 21 13:15:06 wellenflieger avahi-daemon[816]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: A device disappeared from network Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Device eingang disappeared from network Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: A device disappeared from network Dec 21 13:15:06 wellenflieger dhcpcd[944]: wlan0: deleting route to 172.16.0.0/16 Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Device horrorbereich disappeared from network Dec 21 13:15:06 wellenflieger dhcpcd[944]: wlan0: deleting default route via 172.16.0.1 Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Disconnected from remote: 172.16.230.234 Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: A device disappeared from network Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Device dinozelt disappeared from network Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Disconnected from remote: 172.16.200.171 Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: A device disappeared from network Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Device wild-amazonas disappeared from network Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Disconnected from remote: 172.16.4.9 Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: A device disappeared from network Dec 21 13:15:06 wellenflieger volumio[11753]: info: Discovery: Device wellenflieger disappeared from network Dec 21 13:15:07 wellenflieger wpa_supplicant[1199]: wlan0: SME: Trying to authenticate with 78:8a:20:25:14:a6 (SSID='FZL_MM' freq=5805 MHz) Dec 21 13:15:07 wellenflieger kernel: wlan0: authenticate with 78:8a:20:25:14:a6 Dec 21 13:15:07 wellenflieger kernel: wlan0: send auth to 78:8a:20:25:14:a6 (try 1/3) Dec 21 13:15:07 wellenflieger kernel: wlan0: authenticated Dec 21 13:15:07 wellenflieger wpa_supplicant[1199]: wlan0: Trying to associate with 78:8a:20:25:14:a6 (SSID='FZL_MM' freq=5805 MHz) Dec 21 13:15:07 wellenflieger kernel: wlan0: associate with 78:8a:20:25:14:a6 (try 1/3) Dec 21 13:15:07 wellenflieger kernel: wlan0: associate with 78:8a:20:25:14:a6 (try 2/3) Dec 21 13:15:07 wellenflieger kernel: wlan0: RX AssocResp from 78:8a:20:25:14:a6 (capab=0x1511 status=0 aid=1) Dec 21 13:15:07 wellenflieger kernel: wlan0: associated Dec 21 13:15:07 wellenflieger wpa_supplicant[1199]: wlan0: Associated with 78:8a:20:25:14:a6 Dec 21 13:15:07 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 21 13:15:07 wellenflieger kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 78:8a:20:25:14:a6 Dec 21 13:15:08 wellenflieger wpa_supplicant[1199]: wlan0: WPA: Key negotiation completed with 78:8a:20:25:14:a6 [PTK=CCMP GTK=CCMP] Dec 21 13:15:08 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:8a:20:25:14:a6 completed [id=0 id_str=] Dec 21 13:15:08 wellenflieger dhcpcd[944]: wlan0: carrier acquired Dec 21 13:15:08 wellenflieger wpa_supplicant[1199]: RRM: Unexpected neighbor report Dec 21 13:15:08 wellenflieger dhcpcd[944]: wlan0: IAID 6b:e4:fa:f6 Dec 21 13:15:09 wellenflieger volumio[11753]: info: Volumio Network Manager: Network status updated: 0 Dec 21 13:15:09 wellenflieger dhcpcd[944]: wlan0: soliciting an IPv6 router Dec 21 13:15:09 wellenflieger dhcpcd[944]: wlan0: rebinding lease of 172.16.158.58 Dec 21 13:15:09 wellenflieger dhcpcd[944]: wlan0: probing address 172.16.158.58/16 Dec 21 13:15:09 wellenflieger ntpd[11977]: Deleting interface #4 wlan0, 172.16.158.58#123, interface stats: received=10, sent=19, dropped=0, active_time=25 secs Dec 21 13:15:09 wellenflieger ntpd[11977]: 129.250.35.250 local addr 172.16.158.58 -> Dec 21 13:15:09 wellenflieger ntpd[11977]: 81.169.233.252 local addr 172.16.158.58 -> Dec 21 13:15:09 wellenflieger ntpd[11977]: 116.203.244.102 local addr 172.16.158.58 -> Dec 21 13:15:09 wellenflieger ntpd[11977]: 139.162.152.20 local addr 172.16.158.58 -> Dec 21 13:15:09 wellenflieger ntpd[11977]: 176.9.157.155 local addr 172.16.158.58 -> Dec 21 13:15:09 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 13:15:10 wellenflieger volumio[11753]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 21 13:15:10 wellenflieger volumio[11753]: info: CoreCommandRouter::volumioGetState Dec 21 13:15:10 wellenflieger volumio[11753]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:12 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:15:12 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:12 wellenflieger volumio[11753]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 13:15:12 wellenflieger volumio[11753]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:15:12 wellenflieger volumio[11753]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 13:15:12 wellenflieger volumio[11753]: at doSend (dgram.js:714:16) Dec 21 13:15:12 wellenflieger volumio[11753]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 21 13:15:12 wellenflieger volumio[11753]: at afterDns (dgram.js:660:5) Dec 21 13:15:12 wellenflieger volumio[11753]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 21 13:15:12 wellenflieger volumio[11753]: errno: -101, Dec 21 13:15:12 wellenflieger volumio[11753]: code: 'ENETUNREACH', Dec 21 13:15:12 wellenflieger volumio[11753]: syscall: 'send', Dec 21 13:15:12 wellenflieger volumio[11753]: address: '255.255.255.255', Dec 21 13:15:12 wellenflieger volumio[11753]: port: 3483 Dec 21 13:15:12 wellenflieger volumio[11753]: } Dec 21 13:15:12 wellenflieger volumio[11753]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:15:12 wellenflieger sudo[12171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 13:14 Dec 21 13:15:12 wellenflieger sudo[12171]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:12 wellenflieger sudo[12171]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:12 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 21 13:15:12 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 21 13:15:12 wellenflieger systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 21 13:15:12 wellenflieger systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 21 13:15:12 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:15:12 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:15:13 wellenflieger systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 21 13:15:13 wellenflieger systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Dec 21 13:15:13 wellenflieger systemd[1]: Stopped Volumio Backend Module. Dec 21 13:15:13 wellenflieger systemd[1]: Started Volumio Backend Module. Dec 21 13:15:13 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:15:13 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-BEACON-LOSS Dec 21 13:15:13 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:15:13 wellenflieger dhcpcd[944]: wlan0: carrier lost Dec 21 13:15:13 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-DISCONNECTED bssid=78:8a:20:25:14:a6 reason=4 locally_generated=1 Dec 21 13:15:13 wellenflieger kernel: wlan0: authenticate with 18:e8:29:58:2a:e4 Dec 21 13:15:13 wellenflieger wpa_supplicant[1199]: wlan0: SME: Trying to authenticate with 18:e8:29:58:2a:e4 (SSID='FZL_MM' freq=5805 MHz) Dec 21 13:15:13 wellenflieger volumio[12184]: info: ------------------------------------------- Dec 21 13:15:13 wellenflieger volumio[12184]: info: ----- Volumio3 ---- Dec 21 13:15:13 wellenflieger volumio[12184]: info: ------------------------------------------- Dec 21 13:15:13 wellenflieger volumio[12184]: info: ----- System startup ---- Dec 21 13:15:13 wellenflieger volumio[12184]: info: ------------------------------------------- Dec 21 13:15:14 wellenflieger volumio[12184]: info: MYVOLUMIO Environment detected Dec 21 13:15:14 wellenflieger volumio[12184]: info: Plugin folders cleanup Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning into folder /volumio/app/plugins/ Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning category audio_interface Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning category miscellanea Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning category music_service Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning category plugins.json Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning category system_controller Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning category user_interface Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning into folder /data/plugins/ Dec 21 13:15:14 wellenflieger volumio[12184]: info: Scanning category music_service Dec 21 13:15:14 wellenflieger volumio[12184]: info: Plugin folders cleanup completed Dec 21 13:15:14 wellenflieger volumio[12184]: info: ------------------------------------------- Dec 21 13:15:14 wellenflieger volumio[12184]: info: ----- Core plugins startup ---- Dec 21 13:15:14 wellenflieger volumio[12184]: info: ------------------------------------------- Dec 21 13:15:14 wellenflieger volumio[12184]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 13:15:14 wellenflieger volumio[12184]: info: Adding plugin upnp to MyMusic Plugins Dec 21 13:15:14 wellenflieger volumio[12184]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 13:15:14 wellenflieger volumio[12184]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 13:15:14 wellenflieger volumio[12184]: info: Loading plugins from folder /data/plugins/ Dec 21 13:15:14 wellenflieger volumio[12184]: info: Loading plugin "system"... Dec 21 13:15:14 wellenflieger kernel: wlan0: send auth to 18:e8:29:58:2a:e4 (try 1/3) Dec 21 13:15:14 wellenflieger volumio[12184]: info: Loading plugin "appearance"... Dec 21 13:15:14 wellenflieger wpa_supplicant[1199]: wlan0: Trying to associate with 18:e8:29:58:2a:e4 (SSID='FZL_MM' freq=5805 MHz) Dec 21 13:15:14 wellenflieger kernel: wlan0: send auth to 18:e8:29:58:2a:e4 (try 2/3) Dec 21 13:15:14 wellenflieger kernel: wlan0: authenticated Dec 21 13:15:14 wellenflieger kernel: wlan0: associate with 18:e8:29:58:2a:e4 (try 1/3) Dec 21 13:15:14 wellenflieger kernel: wlan0: RX AssocResp from 18:e8:29:58:2a:e4 (capab=0x1511 status=0 aid=1) Dec 21 13:15:14 wellenflieger kernel: wlan0: associated Dec 21 13:15:14 wellenflieger wpa_supplicant[1199]: wlan0: Associated with 18:e8:29:58:2a:e4 Dec 21 13:15:14 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 21 13:15:14 wellenflieger wpa_supplicant[1199]: wlan0: WPA: Key negotiation completed with 18:e8:29:58:2a:e4 [PTK=CCMP GTK=CCMP] Dec 21 13:15:14 wellenflieger wpa_supplicant[1199]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:e8:29:58:2a:e4 completed [id=0 id_str=] Dec 21 13:15:14 wellenflieger wpa_supplicant[1199]: RRM: Unexpected neighbor report Dec 21 13:15:14 wellenflieger dhcpcd[944]: wlan0: carrier acquired Dec 21 13:15:14 wellenflieger dhcpcd[944]: wlan0: IAID 6b:e4:fa:f6 Dec 21 13:15:14 wellenflieger kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 18:e8:29:58:2a:e4 Dec 21 13:15:14 wellenflieger dhcpcd[944]: wlan0: soliciting an IPv6 router Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "network"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Refreshing Cached IP Addresses Dec 21 13:15:15 wellenflieger sudo[12226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 13:15:15 wellenflieger sudo[12226]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:15 wellenflieger sudo[12226]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:15 wellenflieger sudo[12229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 13:15:15 wellenflieger sudo[12229]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "services"... Dec 21 13:15:15 wellenflieger sudo[12229]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "alsa_controller"... Dec 21 13:15:15 wellenflieger sudo[12237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 13:15:15 wellenflieger dhcpcd[944]: wlan0: rebinding lease of 172.16.158.58 Dec 21 13:15:15 wellenflieger sudo[12237]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:15 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "wizard"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "networkfs"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Starting Udev Watcher for removable devices Dec 21 13:15:15 wellenflieger volumio[12184]: info: Ignoring mount for partition: boot Dec 21 13:15:15 wellenflieger volumio[12184]: info: Ignoring mount for partition: volumio Dec 21 13:15:15 wellenflieger volumio[12184]: info: Ignoring mount for partition: volumio_data Dec 21 13:15:15 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "volumio_command_line_client"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "upnp"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: [1766319315372] Starting Upmpd Daemon Dec 21 13:15:15 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "my_music"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "mpd"... Dec 21 13:15:15 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 1 Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "upnp_browser"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "alarm-clock"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "airplay_emulation"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Starting Shairport Sync Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "last_100"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "webradio"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "i2s_dacs"... Dec 21 13:15:15 wellenflieger volumio[12184]: info: I2S DAC not set, start Auto-detection Dec 21 13:15:15 wellenflieger volumio[12184]: info: Loading plugin "volumiodiscovery"... Dec 21 13:15:16 wellenflieger volumio[12184]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:16 wellenflieger volumio[12184]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:16 wellenflieger volumio[12184]: *** WARNING *** For more information see Dec 21 13:15:16 wellenflieger volumio[12184]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:16 wellenflieger volumio[12184]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:16 wellenflieger volumio[12184]: *** WARNING *** For more information see Dec 21 13:15:16 wellenflieger node[12184]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:16 wellenflieger node[12184]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:16 wellenflieger node[12184]: *** WARNING *** For more information see Dec 21 13:15:16 wellenflieger node[12184]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:16 wellenflieger node[12184]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:16 wellenflieger node[12184]: *** WARNING *** For more information see Dec 21 13:15:16 wellenflieger volumio[12184]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 13:15:16 wellenflieger volumio[12184]: info: Discovery: Started advertising with name: Wellenflieger Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading plugin "squeezelite_mc"... Dec 21 13:15:16 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading plugin "outputs"... Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading plugin "albumart"... Dec 21 13:15:16 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Dec 21 13:15:16 wellenflieger volumio[12184]: info: Plugin example_plugin is not enabled Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading plugin "inputs"... Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading plugin "updater_comm"... Dec 21 13:15:16 wellenflieger volumio[12184]: Forking 1 albumart workers Dec 21 13:15:16 wellenflieger volumio[12184]: info: Plugin mpdemulation is not enabled Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading plugin "rest_api"... Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading plugin "websocket"... Dec 21 13:15:16 wellenflieger volumio[12184]: info: Starting Socket.io Server version 2.3.0 Dec 21 13:15:16 wellenflieger volumio[12184]: info: Loading i18n strings for locale de Dec 21 13:15:16 wellenflieger volumio[12184]: Updating browse sources language Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::initPlayerControls Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:16 wellenflieger volumio[12184]: Express server listening on port 3000 Dec 21 13:15:16 wellenflieger volumio[12184]: [Metrics] WebUI: 3s 296.18ms Dec 21 13:15:16 wellenflieger volumio[12184]: info: Setting Device type: x86 Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreStateMachine::resetVolumioState Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreStateMachine::getcurrentVolume Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:15:16 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:16 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Dec 21 13:15:16 wellenflieger volumio[12184]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 21 13:15:17 wellenflieger volumio[12184]: info: Completed loading Core Plugins Dec 21 13:15:17 wellenflieger volumio[12184]: info: Preparing to generate the ALSA configuration file Dec 21 13:15:17 wellenflieger volumio[12184]: info: Cannot read play queue from file Dec 21 13:15:17 wellenflieger volumio[12184]: info: Volumio Network Manager: Network status updated: 0 Dec 21 13:15:17 wellenflieger volumio[12184]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 21 13:15:17 wellenflieger volumio[12184]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 21 13:15:17 wellenflieger volumio[12184]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 21 13:15:17 wellenflieger volumio[12184]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreStateMachine::pushState Dec 21 13:15:17 wellenflieger volumio[12184]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:17 wellenflieger kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreStateMachine::updateTrackBlock Dec 21 13:15:17 wellenflieger volumio[12184]: info: CorePlayQueue::getTrackBlock Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:15:17 wellenflieger volumio[12184]: Starting albumart workers Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreStateMachine::setRepeat null single undefined Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreStateMachine::pushState Dec 21 13:15:17 wellenflieger volumio[12184]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreStateMachine::setRandom null Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreStateMachine::pushState Dec 21 13:15:17 wellenflieger volumio[12184]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:17 wellenflieger volumio[12184]: info: Asound.conf file unchanged, so no further update is needed Dec 21 13:15:17 wellenflieger volumio[12184]: info: Output device has changed, restarting MPD Dec 21 13:15:17 wellenflieger volumio[12184]: info: Output device has changed, restarting Shairport Sync Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:17 wellenflieger sudo[12277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:15:17 wellenflieger sudo[12277]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:17 wellenflieger sudo[12277]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:17 wellenflieger sudo[12279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:15:17 wellenflieger sudo[12279]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:17 wellenflieger systemd[1]: Stopping Music Player Daemon... Dec 21 13:15:17 wellenflieger volumio[12184]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:15:17 wellenflieger volumio[12184]: info: ___________ START PLUGINS ___________ Dec 21 13:15:17 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:15:17 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:15:17 wellenflieger volumio[12184]: info: ControllerMpd::onStart: Initializing MPD Dec 21 13:15:17 wellenflieger volumio[12184]: info: Creating MPD Configuration file Dec 21 13:15:17 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:15:17 wellenflieger sudo[12287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:15:17 wellenflieger sudo[12287]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:17 wellenflieger sudo[12285]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:15:17 wellenflieger sudo[12285]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:17 wellenflieger sudo[12285]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:17 wellenflieger sudo[12287]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:15:17 wellenflieger volumio[12184]: info: [1766319317484] CoreMusicLibrary::Adding element Medienserver Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:17 wellenflieger sudo[12292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:15:17 wellenflieger sudo[12292]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:17 wellenflieger systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 21 13:15:17 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:15:17 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:15:17 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:15:17 wellenflieger volumio[12184]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:17 wellenflieger sudo[12297]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:15:17 wellenflieger sudo[12297]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:17 wellenflieger sudo[12297]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:17 wellenflieger volumio[12184]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:15:17 wellenflieger volumio[12184]: info: [1766319317697] CoreMusicLibrary::Adding element Last_100 Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:15:17 wellenflieger volumio[12184]: info: [1766319317700] CoreMusicLibrary::Adding element Webradio Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:15:17 wellenflieger volumio[12184]: info: Initializing BBC Radios Dec 21 13:15:17 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:17] [connect] Successful connection Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:15:17 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:17 wellenflieger volumio[12184]: info: [squeezelite_mc] Starting proxy server... Dec 21 13:15:17 wellenflieger volumio[12184]: info: Volumio Calling Home Dec 21 13:15:17 wellenflieger volumio[12184]: info: [squeezelite_mc] Proxy server started on port 40099 Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:15:18 wellenflieger volumio[12184]: Unhandled rejection Error: No sockets available, cannot start. Dec 21 13:15:18 wellenflieger volumio[12184]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 21 13:15:18 wellenflieger volumio[12184]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 21 13:15:18 wellenflieger volumio[12184]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 21 13:15:18 wellenflieger volumio[12184]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 21 13:15:18 wellenflieger volumio[12184]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 21 13:15:18 wellenflieger volumio[12184]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 21 13:15:18 wellenflieger volumio[12184]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 21 13:15:18 wellenflieger volumio[12184]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 21 13:15:18 wellenflieger volumio[12184]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 21 13:15:18 wellenflieger volumio[12184]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 21 13:15:18 wellenflieger volumio[12184]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 21 13:15:18 wellenflieger volumio[12184]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 21 13:15:18 wellenflieger volumio[12184]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 21 13:15:18 wellenflieger volumio[12184]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 21 13:15:18 wellenflieger volumio[12184]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 21 13:15:18 wellenflieger volumio[12184]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 13:15:18 wellenflieger volumio[12184]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreStateMachine::pushState Dec 21 13:15:18 wellenflieger volumio[12184]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:18 wellenflieger volumio[12184]: info: MPD Permissions set Dec 21 13:15:18 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766319317 101 Dec 21 13:15:18 wellenflieger volumio[12184]: 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: 3 Dec 21 13:15:18 wellenflieger volumio[12184]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger mpd[12302]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 13:15:18 wellenflieger mpd[12302]: output: No 'audio_output' defined in config file Dec 21 13:15:18 wellenflieger volumio[12184]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreStateMachine::pushState Dec 21 13:15:18 wellenflieger volumio[12184]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:18 wellenflieger mpd[12302]: couldn't open play stream: Device or resource busy Dec 21 13:15:18 wellenflieger mpd[12302]: sndio_output: Error opening default sndio device Dec 21 13:15:18 wellenflieger mpd[12302]: alsa_output: Error opening default ALSA device: Device or resource busy Dec 21 13:15:18 wellenflieger mpd[12302]: output: Successfully detected a jack audio device Dec 21 13:15:18 wellenflieger mpd[12302]: zeroconf: No global port, disabling zeroconf Dec 21 13:15:18 wellenflieger systemd[1]: Started Music Player Daemon. Dec 21 13:15:18 wellenflieger sudo[12292]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:18 wellenflieger sudo[12279]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:18 wellenflieger volumio[12184]: info: [squeezelite_mc] Server discovery started Dec 21 13:15:18 wellenflieger volumio[12184]: info: [squeezelite_mc] Player finder started Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:18 wellenflieger volumio[12184]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 21 13:15:18 wellenflieger volumio[12184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:15:18 wellenflieger volumio[12184]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 13:15:18 wellenflieger volumio[12184]: at doSend (dgram.js:714:16) Dec 21 13:15:18 wellenflieger volumio[12184]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 21 13:15:18 wellenflieger volumio[12184]: at afterDns (dgram.js:660:5) Dec 21 13:15:18 wellenflieger volumio[12184]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 21 13:15:18 wellenflieger volumio[12184]: errno: -101, Dec 21 13:15:18 wellenflieger volumio[12184]: code: 'ENETUNREACH', Dec 21 13:15:18 wellenflieger volumio[12184]: syscall: 'send', Dec 21 13:15:18 wellenflieger volumio[12184]: address: '255.255.255.255', Dec 21 13:15:18 wellenflieger volumio[12184]: port: 3483 Dec 21 13:15:18 wellenflieger volumio[12184]: } Dec 21 13:15:18 wellenflieger volumio[12184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:15:18 wellenflieger sudo[12353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 13:14 Dec 21 13:15:18 wellenflieger sudo[12353]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:18 wellenflieger sudo[12353]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:18 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 21 13:15:18 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 21 13:15:18 wellenflieger systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 21 13:15:18 wellenflieger sudo[12237]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:18 wellenflieger systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 21 13:15:18 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:15:18 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:15:19 wellenflieger systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 21 13:15:19 wellenflieger systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Dec 21 13:15:19 wellenflieger systemd[1]: Stopped Volumio Backend Module. Dec 21 13:15:19 wellenflieger systemd[1]: Started Volumio Backend Module. Dec 21 13:15:19 wellenflieger systemd[1]: Started dynamicswap service. Dec 21 13:15:19 wellenflieger systemd[1]: dynamicswap.service: Succeeded. Dec 21 13:15:19 wellenflieger dhcpcd[944]: wlan0: probing address 172.16.158.58/16 Dec 21 13:15:19 wellenflieger volumio[12367]: info: ------------------------------------------- Dec 21 13:15:19 wellenflieger volumio[12367]: info: ----- Volumio3 ---- Dec 21 13:15:19 wellenflieger volumio[12367]: info: ------------------------------------------- Dec 21 13:15:19 wellenflieger volumio[12367]: info: ----- System startup ---- Dec 21 13:15:19 wellenflieger volumio[12367]: info: ------------------------------------------- Dec 21 13:15:20 wellenflieger volumio[12367]: info: MYVOLUMIO Environment detected Dec 21 13:15:20 wellenflieger volumio[12367]: info: Plugin folders cleanup Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning into folder /volumio/app/plugins/ Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning category audio_interface Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning category miscellanea Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning category music_service Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning category plugins.json Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning category system_controller Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning category user_interface Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning into folder /data/plugins/ Dec 21 13:15:20 wellenflieger volumio[12367]: info: Scanning category music_service Dec 21 13:15:20 wellenflieger volumio[12367]: info: Plugin folders cleanup completed Dec 21 13:15:20 wellenflieger volumio[12367]: info: ------------------------------------------- Dec 21 13:15:20 wellenflieger volumio[12367]: info: ----- Core plugins startup ---- Dec 21 13:15:20 wellenflieger volumio[12367]: info: ------------------------------------------- Dec 21 13:15:20 wellenflieger volumio[12367]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 13:15:20 wellenflieger volumio[12367]: info: Adding plugin upnp to MyMusic Plugins Dec 21 13:15:20 wellenflieger volumio[12367]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 13:15:20 wellenflieger volumio[12367]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 13:15:20 wellenflieger volumio[12367]: info: Loading plugins from folder /data/plugins/ Dec 21 13:15:20 wellenflieger volumio[12367]: info: Loading plugin "system"... Dec 21 13:15:20 wellenflieger volumio[12367]: info: Loading plugin "appearance"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "network"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Refreshing Cached IP Addresses Dec 21 13:15:21 wellenflieger sudo[12394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 13:15:21 wellenflieger sudo[12394]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:21 wellenflieger sudo[12394]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:21 wellenflieger sudo[12397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 13:15:21 wellenflieger sudo[12397]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:21 wellenflieger sudo[12397]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "services"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "alsa_controller"... Dec 21 13:15:21 wellenflieger sudo[12409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 13:15:21 wellenflieger sudo[12409]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:21 wellenflieger sudo[12409]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:21 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "wizard"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "networkfs"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Starting Udev Watcher for removable devices Dec 21 13:15:21 wellenflieger volumio[12367]: info: Ignoring mount for partition: boot Dec 21 13:15:21 wellenflieger volumio[12367]: info: Ignoring mount for partition: volumio Dec 21 13:15:21 wellenflieger volumio[12367]: info: Ignoring mount for partition: volumio_data Dec 21 13:15:21 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "volumio_command_line_client"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "upnp"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: [1766319321405] Starting Upmpd Daemon Dec 21 13:15:21 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "my_music"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "mpd"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "upnp_browser"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "alarm-clock"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "airplay_emulation"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Starting Shairport Sync Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "last_100"... Dec 21 13:15:21 wellenflieger volumio[12367]: info: Loading plugin "webradio"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "i2s_dacs"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: I2S DAC not set, start Auto-detection Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "volumiodiscovery"... Dec 21 13:15:22 wellenflieger volumio[12367]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:22 wellenflieger node[12367]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:22 wellenflieger volumio[12367]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:22 wellenflieger volumio[12367]: *** WARNING *** For more information see Dec 21 13:15:22 wellenflieger volumio[12367]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:22 wellenflieger volumio[12367]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:22 wellenflieger volumio[12367]: *** WARNING *** For more information see Dec 21 13:15:22 wellenflieger node[12367]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:22 wellenflieger node[12367]: *** WARNING *** For more information see Dec 21 13:15:22 wellenflieger node[12367]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 13:15:22 wellenflieger node[12367]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 13:15:22 wellenflieger node[12367]: *** WARNING *** For more information see Dec 21 13:15:22 wellenflieger volumio[12367]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 13:15:22 wellenflieger volumio[12367]: info: Discovery: Started advertising with name: Wellenflieger Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "squeezelite_mc"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "outputs"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "albumart"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: Plugin example_plugin is not enabled Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "inputs"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "updater_comm"... Dec 21 13:15:22 wellenflieger volumio[12367]: Forking 1 albumart workers Dec 21 13:15:22 wellenflieger volumio[12367]: info: Plugin mpdemulation is not enabled Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "rest_api"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading plugin "websocket"... Dec 21 13:15:22 wellenflieger volumio[12367]: info: Starting Socket.io Server version 2.3.0 Dec 21 13:15:22 wellenflieger volumio[12367]: info: Loading i18n strings for locale de Dec 21 13:15:22 wellenflieger volumio[12367]: Updating browse sources language Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::initPlayerControls Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:22 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:23 wellenflieger volumio[12367]: Express server listening on port 3000 Dec 21 13:15:23 wellenflieger volumio[12367]: [Metrics] WebUI: 3s 360.87ms Dec 21 13:15:23 wellenflieger volumio[12367]: info: Setting Device type: x86 Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreStateMachine::resetVolumioState Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreStateMachine::getcurrentVolume Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:23 wellenflieger volumio[12367]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 21 13:15:23 wellenflieger volumio[12367]: info: Completed loading Core Plugins Dec 21 13:15:23 wellenflieger volumio[12367]: info: Preparing to generate the ALSA configuration file Dec 21 13:15:23 wellenflieger volumio[12367]: info: Cannot read play queue from file Dec 21 13:15:23 wellenflieger volumio[12367]: info: Volumio Network Manager: Network status updated: 0 Dec 21 13:15:23 wellenflieger volumio[12367]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 21 13:15:23 wellenflieger volumio[12367]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 21 13:15:23 wellenflieger volumio[12367]: info: Cannot use regular scanning, forcing with ap-force Dec 21 13:15:23 wellenflieger sudo[12441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 21 13:15:23 wellenflieger sudo[12441]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:23 wellenflieger sudo[12441]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:23 wellenflieger volumio[12367]: command failed: Device or resource busy (-16) Dec 21 13:15:23 wellenflieger volumio[12367]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 21 13:15:23 wellenflieger volumio[12367]: command failed: Device or resource busy (-16) Dec 21 13:15:23 wellenflieger volumio[12367]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 21 13:15:23 wellenflieger volumio[12367]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 21 13:15:23 wellenflieger volumio[12367]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreStateMachine::pushState Dec 21 13:15:23 wellenflieger volumio[12367]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreStateMachine::updateTrackBlock Dec 21 13:15:23 wellenflieger volumio[12367]: info: CorePlayQueue::getTrackBlock Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:15:23 wellenflieger volumio[12367]: Starting albumart workers Dec 21 13:15:23 wellenflieger volumio[12367]: info: Asound.conf file unchanged, so no further update is needed Dec 21 13:15:23 wellenflieger volumio[12367]: info: Output device has changed, restarting MPD Dec 21 13:15:23 wellenflieger sudo[12449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:15:23 wellenflieger sudo[12449]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:23 wellenflieger sudo[12449]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:23 wellenflieger volumio[12367]: info: Output device has changed, restarting Shairport Sync Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:23 wellenflieger sudo[12452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:15:23 wellenflieger sudo[12452]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:23 wellenflieger systemd[1]: Stopping Music Player Daemon... Dec 21 13:15:23 wellenflieger volumio[12367]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:15:23 wellenflieger volumio[12367]: info: ___________ START PLUGINS ___________ Dec 21 13:15:23 wellenflieger volumio[12367]: info: ControllerMpd::onStart: Initializing MPD Dec 21 13:15:23 wellenflieger volumio[12367]: info: Creating MPD Configuration file Dec 21 13:15:23 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:15:23 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:15:23 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:15:23 wellenflieger sudo[12459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 13:15:23 wellenflieger sudo[12459]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:15:23 wellenflieger volumio[12367]: info: [1766319323519] CoreMusicLibrary::Adding element Medienserver Dec 21 13:15:23 wellenflieger sudo[12459]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:23 wellenflieger sudo[12457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:15:23 wellenflieger sudo[12457]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:23 wellenflieger sudo[12457]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:23 wellenflieger sudo[12462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 13:15:23 wellenflieger sudo[12462]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:23 wellenflieger systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 21 13:15:23 wellenflieger systemd[1]: mpd.service: Succeeded. Dec 21 13:15:23 wellenflieger systemd[1]: Stopped Music Player Daemon. Dec 21 13:15:23 wellenflieger systemd[1]: Starting Music Player Daemon... Dec 21 13:15:23 wellenflieger volumio[12367]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:23 wellenflieger sudo[12467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 13:15:23 wellenflieger sudo[12467]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:23 wellenflieger sudo[12467]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:23 wellenflieger volumio[12367]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:15:23 wellenflieger volumio[12367]: info: [1766319323717] CoreMusicLibrary::Adding element Last_100 Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 13:15:23 wellenflieger volumio[12367]: info: [1766319323725] CoreMusicLibrary::Adding element Webradio Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:15:23 wellenflieger volumio[12367]: info: Initializing BBC Radios Dec 21 13:15:23 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:23] [connect] Successful connection Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 13:15:23 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 13:15:23 wellenflieger volumio[12367]: info: [squeezelite_mc] Starting proxy server... Dec 21 13:15:23 wellenflieger volumio[12367]: info: Volumio Calling Home Dec 21 13:15:23 wellenflieger kernel: perf: interrupt took too long (2533 > 2500), lowering kernel.perf_event_max_sample_rate to 78750 Dec 21 13:15:24 wellenflieger volumio[12367]: info: [squeezelite_mc] Proxy server started on port 33537 Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreStateMachine::setRepeat null single undefined Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreStateMachine::pushState Dec 21 13:15:24 wellenflieger volumio[12367]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreStateMachine::setRandom null Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreStateMachine::pushState Dec 21 13:15:24 wellenflieger volumio[12367]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:24 wellenflieger volumio[12367]: Unhandled rejection Error: No sockets available, cannot start. Dec 21 13:15:24 wellenflieger volumio[12367]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 21 13:15:24 wellenflieger volumio[12367]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 21 13:15:24 wellenflieger volumio[12367]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 21 13:15:24 wellenflieger volumio[12367]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 21 13:15:24 wellenflieger volumio[12367]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 21 13:15:24 wellenflieger volumio[12367]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 21 13:15:24 wellenflieger volumio[12367]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 21 13:15:24 wellenflieger volumio[12367]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 21 13:15:24 wellenflieger volumio[12367]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 21 13:15:24 wellenflieger volumio[12367]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 21 13:15:24 wellenflieger volumio[12367]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 21 13:15:24 wellenflieger volumio[12367]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 21 13:15:24 wellenflieger volumio[12367]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 21 13:15:24 wellenflieger volumio[12367]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 21 13:15:24 wellenflieger volumio[12367]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 21 13:15:24 wellenflieger volumio[12367]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 13:15:24 wellenflieger volumio[12367]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreStateMachine::pushState Dec 21 13:15:24 wellenflieger volumio[12367]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:24 wellenflieger volumio[12367]: info: MPD Permissions set Dec 21 13:15:24 wellenflieger volumio[12367]: info: MPD Permissions set Dec 21 13:15:24 wellenflieger volumio-remote-updater[805]: [2025-12-21 13:15:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766319323 101 Dec 21 13:15:24 wellenflieger volumio[12367]: 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 21 13:15:24 wellenflieger volumio[12367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 21 13:15:24 wellenflieger volumio[12367]: info: VolumeController:: Volume=100 Mute =false Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreStateMachine::pushState Dec 21 13:15:24 wellenflieger volumio[12367]: info: CorePlayQueue::getTrack 0 Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreCommandRouter::volumioPushState Dec 21 13:15:24 wellenflieger volumio[12367]: info: [squeezelite_mc] Server discovery started Dec 21 13:15:24 wellenflieger volumio[12367]: info: [squeezelite_mc] Player finder started Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 13:15:24 wellenflieger volumio[12367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 13:15:24 wellenflieger volumio[12367]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 21 13:15:24 wellenflieger volumio[12367]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:15:24 wellenflieger volumio[12367]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 21 13:15:24 wellenflieger volumio[12367]: at doSend (dgram.js:714:16) Dec 21 13:15:24 wellenflieger volumio[12367]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Dec 21 13:15:24 wellenflieger volumio[12367]: at afterDns (dgram.js:660:5) Dec 21 13:15:24 wellenflieger volumio[12367]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Dec 21 13:15:24 wellenflieger volumio[12367]: errno: -101, Dec 21 13:15:24 wellenflieger volumio[12367]: code: 'ENETUNREACH', Dec 21 13:15:24 wellenflieger volumio[12367]: syscall: 'send', Dec 21 13:15:24 wellenflieger volumio[12367]: address: '255.255.255.255', Dec 21 13:15:24 wellenflieger volumio[12367]: port: 3483 Dec 21 13:15:24 wellenflieger volumio[12367]: } Dec 21 13:15:24 wellenflieger volumio[12367]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 13:15:24 wellenflieger mpd[12474]: Dec 21 13:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 13:15:24 wellenflieger systemd[1]: Started Music Player Daemon. Dec 21 13:15:24 wellenflieger sudo[12452]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:24 wellenflieger sudo[12462]: pam_unix(sudo:session): session closed for user root Dec 21 13:15:24 wellenflieger sudo[12515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 13:14 Dec 21 13:15:24 wellenflieger sudo[12515]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 13:15:24 wellenflieger dhcpcd[944]: wlan0: leased 172.16.158.58 for 86400 seconds Dec 21 13:15:24 wellenflieger dhcpcd[944]: wlan0: adding route to 172.16.0.0/16 Dec 21 13:15:24 wellenflieger dhcpcd[944]: wlan0: adding default route via 172.16.0.1 Dec 21 13:15:24 wellenflieger avahi-daemon[816]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.158.58. Dec 21 13:15:24 wellenflieger avahi-daemon[816]: New relevant interface wlan0.IPv4 for mDNS. Dec 21 13:15:24 wellenflieger avahi-daemon[816]: Registering new address record for 172.16.158.58 on wlan0.IPv4. 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"