-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-06-17 15:28:57 CEST. -- Jun 17 15:27:34 volumiokeller wpa_supplicant[899]: RRM: Ignoring radio measurement request: Not RRM network Jun 17 15:27:36 volumiokeller wpa_supplicant[899]: RRM: Ignoring radio measurement request: Not RRM network Jun 17 15:27:41 volumiokeller wpa_supplicant[899]: RRM: Ignoring radio measurement request: Not RRM network Jun 17 15:28:20 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b0:f2:08:ec:0b:28 reason=3 Jun 17 15:28:20 volumiokeller dhcpcd[911]: wlan0: carrier lost Jun 17 15:28:20 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 17 15:28:20 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Jun 17 15:28:20 volumiokeller avahi-daemon[583]: Withdrawing address record for 192.168.178.130 on wlan0. Jun 17 15:28:20 volumiokeller avahi-daemon[583]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.130. Jun 17 15:28:20 volumiokeller dhcpcd[911]: wlan0: deleting route to 192.168.178.0/24 Jun 17 15:28:20 volumiokeller dhcpcd[911]: wlan0: deleting default route via 192.168.178.1 Jun 17 15:28:20 volumiokeller avahi-daemon[583]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 17 15:28:20 volumiokeller volumio[23369]: info: Discovery: A device disappeared from network Jun 17 15:28:20 volumiokeller volumio[23369]: info: Discovery: Device volumiowohnzimmer disappeared from network Jun 17 15:28:20 volumiokeller volumio[23369]: info: Discovery: Disconnected from remote: 192.168.178.145 Jun 17 15:28:20 volumiokeller volumio[23369]: info: Discovery: A device disappeared from network Jun 17 15:28:20 volumiokeller volumio[23369]: info: Discovery: Device volumiokeller disappeared from network Jun 17 15:28:22 volumiokeller ntpd[748]: Deleting interface #51 wlan0, 192.168.178.130#123, interface stats: received=248, sent=248, dropped=0, active_time=16201 secs Jun 17 15:28:22 volumiokeller ntpd[748]: 85.220.190.246 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 141.144.246.224 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 130.162.222.153 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 87.106.25.161 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 158.180.28.150 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 185.232.69.65 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 157.90.24.29 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 212.18.3.18 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 128.140.109.119 local addr 192.168.178.130 -> Jun 17 15:28:22 volumiokeller ntpd[748]: 138.201.117.193 local addr 192.168.178.130 -> Jun 17 15:28:23 volumiokeller volumio[23369]: info: Volumio Network Manager: Network status updated: 0 Jun 17 15:28:24 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 17 15:28:24 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 17 15:28:24 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 17 15:28:24 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 17 15:28:26 volumiokeller wpa_supplicant[899]: wlan0: Trying to associate with SSID 'FRITZ!Box 5590 SF' Jun 17 15:28:26 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 17 15:28:26 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 17 15:28:26 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 17 15:28:26 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 17 15:28:26 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 17 15:28:26 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 17 15:28:26 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 17 15:28:28 volumiokeller volumio[23369]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:28 volumiokeller volumio[23369]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 17 15:28:28 volumiokeller volumio[23369]: at doSend (dgram.js:692:16) Jun 17 15:28:28 volumiokeller volumio[23369]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 17 15:28:28 volumiokeller volumio[23369]: at afterDns (dgram.js:638:5) Jun 17 15:28:28 volumiokeller volumio[23369]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 17 15:28:28 volumiokeller volumio[23369]: errno: -101, Jun 17 15:28:28 volumiokeller volumio[23369]: code: 'ENETUNREACH', Jun 17 15:28:28 volumiokeller volumio[23369]: syscall: 'send', Jun 17 15:28:28 volumiokeller volumio[23369]: address: '255.255.255.255', Jun 17 15:28:28 volumiokeller volumio[23369]: port: 3483 Jun 17 15:28:28 volumiokeller volumio[23369]: } Jun 17 15:28:28 volumiokeller volumio[23369]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:28 volumiokeller sudo[32529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 15:27 Jun 17 15:28:28 volumiokeller sudo[32529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:29 volumiokeller sudo[32529]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:29 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 17 15:28:29 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 17 15:28:29 volumiokeller systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 17 15:28:29 volumiokeller go-librespot[23561]: time="2025-06-17T15:28:29+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 17 15:28:29 volumiokeller systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 17 15:28:29 volumiokeller systemd[1]: Started dynamicswap service. Jun 17 15:28:29 volumiokeller systemd[1]: dynamicswap.service: Succeeded. Jun 17 15:28:29 volumiokeller systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 17 15:28:29 volumiokeller systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Jun 17 15:28:29 volumiokeller systemd[1]: Started dynamicswap service. Jun 17 15:28:29 volumiokeller systemd[1]: Stopped Volumio Backend Module. Jun 17 15:28:29 volumiokeller systemd[1]: Started Volumio Backend Module. Jun 17 15:28:29 volumiokeller systemd[1]: dynamicswap.service: Succeeded. Jun 17 15:28:30 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 17 15:28:30 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 17 15:28:30 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 17 15:28:30 volumiokeller volumio[32544]: info: ------------------------------------------- Jun 17 15:28:30 volumiokeller volumio[32544]: info: ----- Volumio3 ---- Jun 17 15:28:30 volumiokeller volumio[32544]: info: ------------------------------------------- Jun 17 15:28:30 volumiokeller volumio[32544]: info: ----- System startup ---- Jun 17 15:28:30 volumiokeller volumio[32544]: info: ------------------------------------------- Jun 17 15:28:30 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 17 15:28:31 volumiokeller volumio[32544]: info: MYVOLUMIO Environment detected Jun 17 15:28:31 volumiokeller volumio[32544]: info: Plugin folders cleanup Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning into folder /volumio/app/plugins/ Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning category audio_interface Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning category miscellanea Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning category music_service Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning category plugins.json Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning category system_controller Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning category user_interface Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning into folder /data/plugins/ Jun 17 15:28:31 volumiokeller volumio[32544]: info: Scanning category music_service Jun 17 15:28:31 volumiokeller volumio[32544]: info: Plugin folders cleanup completed Jun 17 15:28:31 volumiokeller volumio[32544]: info: ------------------------------------------- Jun 17 15:28:31 volumiokeller volumio[32544]: info: ----- Core plugins startup ---- Jun 17 15:28:31 volumiokeller volumio[32544]: info: ------------------------------------------- Jun 17 15:28:31 volumiokeller volumio[32544]: info: Loading plugins from folder /volumio/app/plugins/ Jun 17 15:28:31 volumiokeller volumio[32544]: info: Adding plugin upnp to MyMusic Plugins Jun 17 15:28:31 volumiokeller volumio[32544]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 17 15:28:31 volumiokeller volumio[32544]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 17 15:28:31 volumiokeller volumio[32544]: info: Loading plugins from folder /data/plugins/ Jun 17 15:28:31 volumiokeller volumio[32544]: info: Loading plugin "system"... Jun 17 15:28:31 volumiokeller volumio[32544]: info: Loading plugin "appearance"... Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "network"... Jun 17 15:28:32 volumiokeller volumio[32544]: info: Refreshing Cached IP Addresses Jun 17 15:28:32 volumiokeller sudo[32571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 15:28:32 volumiokeller sudo[32571]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:32 volumiokeller sudo[32571]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:32 volumiokeller sudo[32573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 15:28:32 volumiokeller sudo[32573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:32 volumiokeller sudo[32573]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "services"... Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "alsa_controller"... Jun 17 15:28:32 volumiokeller sudo[32586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 17 15:28:32 volumiokeller sudo[32586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:32 volumiokeller volumio[32544]: warn: Unable to locate the audio output device Allo BOSS. Please configure a valid output device. Jun 17 15:28:32 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "wizard"... Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "networkfs"... Jun 17 15:28:32 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 17 15:28:32 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 17 15:28:32 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 17 15:28:32 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 17 15:28:32 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 17 15:28:32 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 17 15:28:32 volumiokeller volumio[32544]: info: Starting Udev Watcher for removable devices Jun 17 15:28:32 volumiokeller volumio[32544]: info: Ignoring mount for partition: boot Jun 17 15:28:32 volumiokeller volumio[32544]: info: Ignoring mount for partition: volumio Jun 17 15:28:32 volumiokeller volumio[32544]: info: Ignoring mount for partition: volumio_data Jun 17 15:28:32 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "volumio_command_line_client"... Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "upnp"... Jun 17 15:28:32 volumiokeller volumio[32544]: info: [1750166912813] Starting Upmpd Daemon Jun 17 15:28:32 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "my_music"... Jun 17 15:28:32 volumiokeller volumio[32544]: info: Loading plugin "mpd"... Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "upnp_browser"... Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "alarm-clock"... Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "airplay_emulation"... Jun 17 15:28:33 volumiokeller volumio[32544]: info: Starting Shairport Sync Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "last_100"... Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "webradio"... Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "i2s_dacs"... Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "volumiodiscovery"... Jun 17 15:28:33 volumiokeller volumio[32544]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:33 volumiokeller volumio[32544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:33 volumiokeller volumio[32544]: *** WARNING *** For more information see Jun 17 15:28:33 volumiokeller node[32544]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:33 volumiokeller volumio[32544]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:33 volumiokeller volumio[32544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:33 volumiokeller volumio[32544]: *** WARNING *** For more information see Jun 17 15:28:33 volumiokeller node[32544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:33 volumiokeller node[32544]: *** WARNING *** For more information see Jun 17 15:28:33 volumiokeller node[32544]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:33 volumiokeller node[32544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:33 volumiokeller node[32544]: *** WARNING *** For more information see Jun 17 15:28:33 volumiokeller volumio[32544]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 17 15:28:33 volumiokeller volumio[32544]: info: Discovery: Started advertising with name: VolumioKeller Jun 17 15:28:33 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:33 volumiokeller volumio[32544]: info: Loading plugin "spop"... Jun 17 15:28:34 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:34] [connect] Successful connection Jun 17 15:28:34 volumiokeller volumio[32544]: info: Loading plugin "squeezelite_mc"... Jun 17 15:28:35 volumiokeller sudo[32586]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:35 volumiokeller nmbd[720]: [2025/06/17 15:28:35.426704, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 17 15:28:35 volumiokeller nmbd[720]: Packet send failed to 192.168.178.255(137) ERRNO=Network is unreachable Jun 17 15:28:35 volumiokeller nmbd[720]: [2025/06/17 15:28:35.426939, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 17 15:28:35 volumiokeller nmbd[720]: send_netbios_packet: send_packet() to IP 192.168.178.255 port 137 failed Jun 17 15:28:35 volumiokeller nmbd[720]: [2025/06/17 15:28:35.427014, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Jun 17 15:28:35 volumiokeller nmbd[720]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Jun 17 15:28:35 volumiokeller volumio[32544]: info: Plugin ytcr is not enabled Jun 17 15:28:35 volumiokeller volumio[32544]: info: Loading plugin "outputs"... Jun 17 15:28:35 volumiokeller volumio[32544]: info: Loading plugin "albumart"... Jun 17 15:28:35 volumiokeller volumio[32544]: info: Plugin example_plugin is not enabled Jun 17 15:28:35 volumiokeller volumio[32544]: info: Loading plugin "inputs"... Jun 17 15:28:35 volumiokeller volumio[32544]: info: Loading plugin "updater_comm"... Jun 17 15:28:35 volumiokeller volumio[32544]: info: Plugin mpdemulation is not enabled Jun 17 15:28:35 volumiokeller volumio[32544]: info: Loading plugin "rest_api"... Jun 17 15:28:35 volumiokeller volumio[32544]: info: Loading plugin "websocket"... Jun 17 15:28:35 volumiokeller volumio[32544]: info: Starting Socket.io Server version 2.3.0 Jun 17 15:28:35 volumiokeller volumio[32544]: info: Loading i18n strings for locale de Jun 17 15:28:35 volumiokeller volumio[32544]: Updating browse sources language Jun 17 15:28:35 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:35 volumiokeller volumio[32544]: Forking 3 albumart workers Jun 17 15:28:35 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:35 volumiokeller volumio[32544]: info: CoreCommandRouter::initPlayerControls Jun 17 15:28:35 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:35 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:35 volumiokeller volumio[32544]: Express server listening on port 3000 Jun 17 15:28:35 volumiokeller volumio[32544]: [Metrics] WebUI: 5s 525.11ms Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::resetVolumioState Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::getcurrentVolume Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:36 volumiokeller volumio[32544]: info: Cannot read play queue from file Jun 17 15:28:36 volumiokeller volumio[32544]: info: Volumio Network Manager: Network status updated: 0 Jun 17 15:28:36 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750166914 101 Jun 17 15:28:36 volumiokeller volumio[32544]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::pushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::updateTrackBlock Jun 17 15:28:36 volumiokeller volumio[32544]: info: CorePlayQueue::getTrackBlock Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::setRepeat null single undefined Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::pushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::setRandom null Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::pushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: Setting Device type: Raspberry PI Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreStateMachine::pushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:36 volumiokeller volumio[32544]: info: Completed loading Core Plugins Jun 17 15:28:36 volumiokeller volumio[32544]: info: Preparing to generate the ALSA configuration file Jun 17 15:28:36 volumiokeller volumio[32544]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 17 15:28:36 volumiokeller volumio[32544]: info: Reading ALSA contributions from plugins. Jun 17 15:28:36 volumiokeller volumio[32544]: info: Asound.conf file unchanged, so no further update is needed Jun 17 15:28:36 volumiokeller volumio[32544]: info: Output device has changed, restarting MPD Jun 17 15:28:36 volumiokeller volumio[32544]: info: Output device has changed, restarting Shairport Sync Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:36 volumiokeller sudo[32646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 15:28:36 volumiokeller sudo[32646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:36 volumiokeller sudo[32646]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:36 volumiokeller sudo[32648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 15:28:36 volumiokeller sudo[32648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:36 volumiokeller systemd[1]: Stopping Music Player Daemon... Jun 17 15:28:36 volumiokeller systemd[1]: mpd.service: Succeeded. Jun 17 15:28:36 volumiokeller systemd[1]: Stopped Music Player Daemon. Jun 17 15:28:36 volumiokeller volumio[32544]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:36 volumiokeller volumio[32544]: info: ___________ START PLUGINS ___________ Jun 17 15:28:36 volumiokeller volumio[32544]: info: ControllerMpd::onStart: Initializing MPD Jun 17 15:28:36 volumiokeller volumio[32544]: info: Creating MPD Configuration file Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:36 volumiokeller systemd[1]: Starting Music Player Daemon... Jun 17 15:28:36 volumiokeller sudo[32657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 15:28:36 volumiokeller sudo[32657]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:36 volumiokeller sudo[32657]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:36 volumiokeller sudo[32660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 15:28:36 volumiokeller sudo[32660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:36 volumiokeller volumio[32544]: info: [1750166916727] CoreMusicLibrary::Adding element Medienserver Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:36 volumiokeller systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 17 15:28:36 volumiokeller systemd[1]: mpd.service: Succeeded. Jun 17 15:28:36 volumiokeller systemd[1]: Stopped Music Player Daemon. Jun 17 15:28:36 volumiokeller systemd[1]: Starting Music Player Daemon... Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:36 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 17 15:28:36 volumiokeller volumio[32544]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:36 volumiokeller volumio[32544]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:36 volumiokeller volumio[32544]: info: [1750166916882] CoreMusicLibrary::Adding element Last_100 Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:36 volumiokeller volumio[32544]: info: [1750166916887] CoreMusicLibrary::Adding element Webradio Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:36 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:36 volumiokeller sudo[32664]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 15:28:36 volumiokeller volumio[32544]: info: Initializing BBC Radios Jun 17 15:28:36 volumiokeller sudo[32664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:36 volumiokeller sudo[32664]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:36 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 17 15:28:37 volumiokeller volumio[32544]: Starting albumart workers Jun 17 15:28:37 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:37 volumiokeller volumio[32544]: info: Creating Spotify config file Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:37 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 17 15:28:37 volumiokeller volumio[32544]: Starting albumart workers Jun 17 15:28:37 volumiokeller volumio[32544]: info: [squeezelite_mc] Starting proxy server... Jun 17 15:28:37 volumiokeller volumio[32544]: info: Volumio Calling Home Jun 17 15:28:37 volumiokeller volumio[32544]: Starting albumart workers Jun 17 15:28:37 volumiokeller volumio[32544]: info: [squeezelite_mc] Proxy server started on port 45451 Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:37 volumiokeller volumio[32544]: Unhandled rejection Error: No sockets available, cannot start. Jun 17 15:28:37 volumiokeller volumio[32544]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 17 15:28:37 volumiokeller volumio[32544]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 17 15:28:37 volumiokeller volumio[32544]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 17 15:28:37 volumiokeller volumio[32544]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 17 15:28:37 volumiokeller volumio[32544]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 17 15:28:37 volumiokeller volumio[32544]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 17 15:28:37 volumiokeller volumio[32544]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 17 15:28:37 volumiokeller volumio[32544]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 17 15:28:37 volumiokeller volumio[32544]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 17 15:28:37 volumiokeller volumio[32544]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 17 15:28:37 volumiokeller volumio[32544]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 17 15:28:37 volumiokeller volumio[32544]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 17 15:28:37 volumiokeller volumio[32544]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 17 15:28:37 volumiokeller volumio[32544]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 17 15:28:37 volumiokeller volumio[32544]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 17 15:28:37 volumiokeller volumio[32544]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 17 15:28:37 volumiokeller volumio[32544]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreStateMachine::pushState Jun 17 15:28:37 volumiokeller volumio[32544]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:37 volumiokeller volumio[32544]: info: [squeezelite_mc] Server discovery started Jun 17 15:28:37 volumiokeller volumio[32544]: info: [squeezelite_mc] Player finder started Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:37 volumiokeller volumio[32544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:37 volumiokeller volumio[32544]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 17 15:28:37 volumiokeller volumio[32544]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:37 volumiokeller volumio[32544]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 17 15:28:37 volumiokeller volumio[32544]: at doSend (dgram.js:692:16) Jun 17 15:28:37 volumiokeller volumio[32544]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 17 15:28:37 volumiokeller volumio[32544]: at afterDns (dgram.js:638:5) Jun 17 15:28:37 volumiokeller volumio[32544]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 17 15:28:37 volumiokeller volumio[32544]: errno: -101, Jun 17 15:28:37 volumiokeller volumio[32544]: code: 'ENETUNREACH', Jun 17 15:28:37 volumiokeller volumio[32544]: syscall: 'send', Jun 17 15:28:37 volumiokeller volumio[32544]: address: '255.255.255.255', Jun 17 15:28:37 volumiokeller volumio[32544]: port: 3483 Jun 17 15:28:37 volumiokeller volumio[32544]: } Jun 17 15:28:37 volumiokeller volumio[32544]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:38 volumiokeller sudo[32727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 15:27 Jun 17 15:28:38 volumiokeller sudo[32727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:38 volumiokeller sudo[32727]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:38 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 17 15:28:38 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 17 15:28:38 volumiokeller systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 17 15:28:38 volumiokeller sudo[32660]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:38 volumiokeller sudo[32648]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:38 volumiokeller systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 17 15:28:38 volumiokeller systemd[1]: Started dynamicswap service. Jun 17 15:28:38 volumiokeller systemd[1]: dynamicswap.service: Succeeded. Jun 17 15:28:38 volumiokeller mpd[32673]: Jun 17 15:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 15:28:38 volumiokeller systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 17 15:28:38 volumiokeller systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Jun 17 15:28:38 volumiokeller systemd[1]: Started Music Player Daemon. Jun 17 15:28:38 volumiokeller systemd[1]: Stopped Volumio Backend Module. Jun 17 15:28:38 volumiokeller systemd[1]: Started Volumio Backend Module. Jun 17 15:28:38 volumiokeller systemd[1]: Started dynamicswap service. Jun 17 15:28:38 volumiokeller systemd[1]: dynamicswap.service: Succeeded. Jun 17 15:28:39 volumiokeller wpa_supplicant[899]: wlan0: Trying to associate with SSID 'FRITZ!Box 5590 SF' Jun 17 15:28:39 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 17 15:28:39 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 17 15:28:39 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 17 15:28:39 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 17 15:28:39 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 17 15:28:39 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 17 15:28:39 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 17 15:28:40 volumiokeller volumio[32743]: info: ------------------------------------------- Jun 17 15:28:40 volumiokeller volumio[32743]: info: ----- Volumio3 ---- Jun 17 15:28:40 volumiokeller volumio[32743]: info: ------------------------------------------- Jun 17 15:28:40 volumiokeller volumio[32743]: info: ----- System startup ---- Jun 17 15:28:40 volumiokeller volumio[32743]: info: ------------------------------------------- Jun 17 15:28:40 volumiokeller volumio[32743]: info: MYVOLUMIO Environment detected Jun 17 15:28:41 volumiokeller volumio[32743]: info: Plugin folders cleanup Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning into folder /volumio/app/plugins/ Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning category audio_interface Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning category miscellanea Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning category music_service Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning category plugins.json Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning category system_controller Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning category user_interface Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning into folder /data/plugins/ Jun 17 15:28:41 volumiokeller volumio[32743]: info: Scanning category music_service Jun 17 15:28:41 volumiokeller volumio[32743]: info: Plugin folders cleanup completed Jun 17 15:28:41 volumiokeller volumio[32743]: info: ------------------------------------------- Jun 17 15:28:41 volumiokeller volumio[32743]: info: ----- Core plugins startup ---- Jun 17 15:28:41 volumiokeller volumio[32743]: info: ------------------------------------------- Jun 17 15:28:41 volumiokeller volumio[32743]: info: Loading plugins from folder /volumio/app/plugins/ Jun 17 15:28:41 volumiokeller volumio[32743]: info: Adding plugin upnp to MyMusic Plugins Jun 17 15:28:41 volumiokeller volumio[32743]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 17 15:28:41 volumiokeller volumio[32743]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 17 15:28:41 volumiokeller volumio[32743]: info: Loading plugins from folder /data/plugins/ Jun 17 15:28:41 volumiokeller volumio[32743]: info: Loading plugin "system"... Jun 17 15:28:41 volumiokeller volumio[32743]: info: Loading plugin "appearance"... Jun 17 15:28:41 volumiokeller volumio[32743]: info: Loading plugin "network"... Jun 17 15:28:41 volumiokeller volumio[32743]: info: Refreshing Cached IP Addresses Jun 17 15:28:42 volumiokeller sudo[302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 15:28:42 volumiokeller sudo[302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:42 volumiokeller sudo[302]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:42 volumiokeller sudo[304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 15:28:42 volumiokeller sudo[304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:42 volumiokeller sudo[304]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "services"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "alsa_controller"... Jun 17 15:28:42 volumiokeller sudo[317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 17 15:28:42 volumiokeller sudo[317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:42 volumiokeller volumio[32743]: warn: Unable to locate the audio output device Allo BOSS. Please configure a valid output device. Jun 17 15:28:42 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "wizard"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "networkfs"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Starting Udev Watcher for removable devices Jun 17 15:28:42 volumiokeller volumio[32743]: info: Ignoring mount for partition: boot Jun 17 15:28:42 volumiokeller volumio[32743]: info: Ignoring mount for partition: volumio Jun 17 15:28:42 volumiokeller volumio[32743]: info: Ignoring mount for partition: volumio_data Jun 17 15:28:42 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "volumio_command_line_client"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "upnp"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: [1750166922189] Starting Upmpd Daemon Jun 17 15:28:42 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "my_music"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "mpd"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "upnp_browser"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "alarm-clock"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "airplay_emulation"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Starting Shairport Sync Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "last_100"... Jun 17 15:28:42 volumiokeller volumio[32743]: info: Loading plugin "webradio"... Jun 17 15:28:43 volumiokeller volumio[32743]: info: Loading plugin "i2s_dacs"... Jun 17 15:28:43 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 17 15:28:43 volumiokeller volumio[32743]: info: Loading plugin "volumiodiscovery"... Jun 17 15:28:43 volumiokeller volumio[32743]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:43 volumiokeller volumio[32743]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:43 volumiokeller volumio[32743]: *** WARNING *** For more information see Jun 17 15:28:43 volumiokeller node[32743]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:43 volumiokeller volumio[32743]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:43 volumiokeller volumio[32743]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:43 volumiokeller volumio[32743]: *** WARNING *** For more information see Jun 17 15:28:43 volumiokeller node[32743]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:43 volumiokeller node[32743]: *** WARNING *** For more information see Jun 17 15:28:43 volumiokeller node[32743]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:43 volumiokeller node[32743]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:43 volumiokeller node[32743]: *** WARNING *** For more information see Jun 17 15:28:43 volumiokeller volumio[32743]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 17 15:28:43 volumiokeller volumio[32743]: info: Discovery: Started advertising with name: VolumioKeller Jun 17 15:28:43 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:43 volumiokeller volumio[32743]: info: Loading plugin "spop"... Jun 17 15:28:43 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 17 15:28:43 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 17 15:28:43 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 17 15:28:44 volumiokeller volumio[32743]: info: Loading plugin "squeezelite_mc"... Jun 17 15:28:44 volumiokeller volumio[32743]: info: Plugin ytcr is not enabled Jun 17 15:28:44 volumiokeller volumio[32743]: info: Loading plugin "outputs"... Jun 17 15:28:44 volumiokeller volumio[32743]: info: Loading plugin "albumart"... Jun 17 15:28:44 volumiokeller volumio[32743]: info: Plugin example_plugin is not enabled Jun 17 15:28:44 volumiokeller volumio[32743]: info: Loading plugin "inputs"... Jun 17 15:28:44 volumiokeller volumio[32743]: info: Loading plugin "updater_comm"... Jun 17 15:28:45 volumiokeller volumio[32743]: info: Plugin mpdemulation is not enabled Jun 17 15:28:45 volumiokeller volumio[32743]: info: Loading plugin "rest_api"... Jun 17 15:28:45 volumiokeller volumio[32743]: info: Loading plugin "websocket"... Jun 17 15:28:45 volumiokeller volumio[32743]: info: Starting Socket.io Server version 2.3.0 Jun 17 15:28:45 volumiokeller volumio[32743]: info: Loading i18n strings for locale de Jun 17 15:28:45 volumiokeller volumio[32743]: Updating browse sources language Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:45 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 17 15:28:45 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 17 15:28:45 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 17 15:28:45 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 17 15:28:45 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 17 15:28:45 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 17 15:28:45 volumiokeller volumio[32743]: Forking 3 albumart workers Jun 17 15:28:45 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:45] [connect] Successful connection Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::initPlayerControls Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: Express server listening on port 3000 Jun 17 15:28:45 volumiokeller volumio[32743]: [Metrics] WebUI: 5s 572.53ms Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::resetVolumioState Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::getcurrentVolume Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:45 volumiokeller volumio[32743]: info: Cannot read play queue from file Jun 17 15:28:45 volumiokeller volumio[32743]: info: Volumio Network Manager: Network status updated: 0 Jun 17 15:28:45 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750166923 101 Jun 17 15:28:45 volumiokeller volumio[32743]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::pushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::updateTrackBlock Jun 17 15:28:45 volumiokeller volumio[32743]: info: CorePlayQueue::getTrackBlock Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::setRepeat null single undefined Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::pushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::setRandom null Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::pushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: Setting Device type: Raspberry PI Jun 17 15:28:45 volumiokeller volumio[32743]: info: Completed loading Core Plugins Jun 17 15:28:45 volumiokeller volumio[32743]: info: Preparing to generate the ALSA configuration file Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreStateMachine::pushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:45 volumiokeller volumio[32743]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 17 15:28:45 volumiokeller volumio[32743]: info: Reading ALSA contributions from plugins. Jun 17 15:28:45 volumiokeller volumio[32743]: info: Asound.conf file unchanged, so no further update is needed Jun 17 15:28:45 volumiokeller volumio[32743]: info: Output device has changed, restarting MPD Jun 17 15:28:45 volumiokeller volumio[32743]: info: Output device has changed, restarting Shairport Sync Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:45 volumiokeller sudo[392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 15:28:45 volumiokeller sudo[392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:45 volumiokeller sudo[392]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:45 volumiokeller sudo[394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 15:28:45 volumiokeller sudo[394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:45 volumiokeller volumio[32743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:45 volumiokeller volumio[32743]: info: ___________ START PLUGINS ___________ Jun 17 15:28:45 volumiokeller systemd[1]: Stopping Music Player Daemon... Jun 17 15:28:45 volumiokeller volumio[32743]: info: ControllerMpd::onStart: Initializing MPD Jun 17 15:28:45 volumiokeller volumio[32743]: info: Creating MPD Configuration file Jun 17 15:28:45 volumiokeller systemd[1]: mpd.service: Succeeded. Jun 17 15:28:45 volumiokeller systemd[1]: Stopped Music Player Daemon. Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:45 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:46 volumiokeller volumio[32743]: info: [1750166926000] CoreMusicLibrary::Adding element Medienserver Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:46 volumiokeller sudo[401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 15:28:46 volumiokeller sudo[401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:46 volumiokeller systemd[1]: Starting Music Player Daemon... Jun 17 15:28:46 volumiokeller sudo[401]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:46 volumiokeller sudo[404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 15:28:46 volumiokeller sudo[404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:46 volumiokeller systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 17 15:28:46 volumiokeller systemd[1]: mpd.service: Succeeded. Jun 17 15:28:46 volumiokeller systemd[1]: Stopped Music Player Daemon. Jun 17 15:28:46 volumiokeller systemd[1]: Starting Music Player Daemon... Jun 17 15:28:46 volumiokeller volumio[32743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:46 volumiokeller sudo[408]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 15:28:46 volumiokeller sudo[408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:46 volumiokeller sudo[408]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:46 volumiokeller volumio[32743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:46 volumiokeller volumio[32743]: info: [1750166926267] CoreMusicLibrary::Adding element Last_100 Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:46 volumiokeller volumio[32743]: info: [1750166926272] CoreMusicLibrary::Adding element Webradio Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:46 volumiokeller volumio[32743]: info: Initializing BBC Radios Jun 17 15:28:46 volumiokeller volumio[32743]: Starting albumart workers Jun 17 15:28:46 volumiokeller volumio[32743]: Starting albumart workers Jun 17 15:28:46 volumiokeller volumio[32743]: Starting albumart workers Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:46 volumiokeller volumio[32743]: info: Creating Spotify config file Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:46 volumiokeller volumio[32743]: info: [squeezelite_mc] Starting proxy server... Jun 17 15:28:46 volumiokeller volumio[32743]: info: Volumio Calling Home Jun 17 15:28:46 volumiokeller volumio[32743]: info: [squeezelite_mc] Proxy server started on port 39183 Jun 17 15:28:46 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:47 volumiokeller volumio[32743]: Unhandled rejection Error: No sockets available, cannot start. Jun 17 15:28:47 volumiokeller volumio[32743]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 17 15:28:47 volumiokeller volumio[32743]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 17 15:28:47 volumiokeller volumio[32743]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 17 15:28:47 volumiokeller volumio[32743]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 17 15:28:47 volumiokeller volumio[32743]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 17 15:28:47 volumiokeller volumio[32743]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 17 15:28:47 volumiokeller volumio[32743]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 17 15:28:47 volumiokeller volumio[32743]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 17 15:28:47 volumiokeller volumio[32743]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 17 15:28:47 volumiokeller volumio[32743]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 17 15:28:47 volumiokeller volumio[32743]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 17 15:28:47 volumiokeller volumio[32743]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 17 15:28:47 volumiokeller volumio[32743]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 17 15:28:47 volumiokeller volumio[32743]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 17 15:28:47 volumiokeller volumio[32743]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 17 15:28:47 volumiokeller volumio[32743]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 17 15:28:47 volumiokeller volumio[32743]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jun 17 15:28:47 volumiokeller volumio[32743]: info: MPD Permissions set Jun 17 15:28:47 volumiokeller volumio[32743]: info: MPD Permissions set Jun 17 15:28:47 volumiokeller volumio[32743]: info: CoreStateMachine::pushState Jun 17 15:28:47 volumiokeller volumio[32743]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:47 volumiokeller volumio[32743]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:47 volumiokeller volumio[32743]: info: [squeezelite_mc] Server discovery started Jun 17 15:28:47 volumiokeller volumio[32743]: info: [squeezelite_mc] Player finder started Jun 17 15:28:47 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:47 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:47 volumiokeller volumio[32743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:47 volumiokeller volumio[32743]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 17 15:28:47 volumiokeller volumio[32743]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:47 volumiokeller volumio[32743]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 17 15:28:47 volumiokeller volumio[32743]: at doSend (dgram.js:692:16) Jun 17 15:28:47 volumiokeller volumio[32743]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 17 15:28:47 volumiokeller volumio[32743]: at afterDns (dgram.js:638:5) Jun 17 15:28:47 volumiokeller volumio[32743]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 17 15:28:47 volumiokeller volumio[32743]: errno: -101, Jun 17 15:28:47 volumiokeller volumio[32743]: code: 'ENETUNREACH', Jun 17 15:28:47 volumiokeller volumio[32743]: syscall: 'send', Jun 17 15:28:47 volumiokeller volumio[32743]: address: '255.255.255.255', Jun 17 15:28:47 volumiokeller volumio[32743]: port: 3483 Jun 17 15:28:47 volumiokeller volumio[32743]: } Jun 17 15:28:47 volumiokeller volumio[32743]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:47 volumiokeller sudo[317]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:47 volumiokeller sudo[473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 15:27 Jun 17 15:28:47 volumiokeller sudo[473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:47 volumiokeller sudo[473]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:48 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 17 15:28:48 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 17 15:28:48 volumiokeller systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 17 15:28:48 volumiokeller sudo[394]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:48 volumiokeller sudo[404]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:48 volumiokeller systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 17 15:28:48 volumiokeller mpd[415]: Jun 17 15:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 15:28:48 volumiokeller systemd[1]: Started dynamicswap service. Jun 17 15:28:48 volumiokeller systemd[1]: Started Music Player Daemon. Jun 17 15:28:48 volumiokeller systemd[1]: dynamicswap.service: Succeeded. Jun 17 15:28:48 volumiokeller systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 17 15:28:48 volumiokeller systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18. Jun 17 15:28:48 volumiokeller systemd[1]: Stopped Volumio Backend Module. Jun 17 15:28:48 volumiokeller systemd[1]: Started Volumio Backend Module. Jun 17 15:28:48 volumiokeller systemd[1]: Started dynamicswap service. Jun 17 15:28:48 volumiokeller systemd[1]: dynamicswap.service: Succeeded. Jun 17 15:28:48 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 17 15:28:49 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 17 15:28:49 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 17 15:28:49 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 17 15:28:49 volumiokeller volumio[488]: info: ------------------------------------------- Jun 17 15:28:49 volumiokeller volumio[488]: info: ----- Volumio3 ---- Jun 17 15:28:49 volumiokeller volumio[488]: info: ------------------------------------------- Jun 17 15:28:49 volumiokeller volumio[488]: info: ----- System startup ---- Jun 17 15:28:49 volumiokeller volumio[488]: info: ------------------------------------------- Jun 17 15:28:50 volumiokeller volumio[488]: info: MYVOLUMIO Environment detected Jun 17 15:28:50 volumiokeller volumio[488]: info: Plugin folders cleanup Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning into folder /volumio/app/plugins/ Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning category audio_interface Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning category miscellanea Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning category music_service Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning category plugins.json Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning category system_controller Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning category user_interface Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning into folder /data/plugins/ Jun 17 15:28:50 volumiokeller volumio[488]: info: Scanning category music_service Jun 17 15:28:50 volumiokeller volumio[488]: info: Plugin folders cleanup completed Jun 17 15:28:50 volumiokeller volumio[488]: info: ------------------------------------------- Jun 17 15:28:50 volumiokeller volumio[488]: info: ----- Core plugins startup ---- Jun 17 15:28:50 volumiokeller volumio[488]: info: ------------------------------------------- Jun 17 15:28:50 volumiokeller volumio[488]: info: Loading plugins from folder /volumio/app/plugins/ Jun 17 15:28:50 volumiokeller volumio[488]: info: Adding plugin upnp to MyMusic Plugins Jun 17 15:28:50 volumiokeller volumio[488]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 17 15:28:50 volumiokeller volumio[488]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 17 15:28:50 volumiokeller volumio[488]: info: Loading plugins from folder /data/plugins/ Jun 17 15:28:50 volumiokeller volumio[488]: info: Loading plugin "system"... Jun 17 15:28:50 volumiokeller volumio[488]: info: Loading plugin "appearance"... Jun 17 15:28:51 volumiokeller wpa_supplicant[899]: wlan0: Trying to associate with SSID 'FRITZ!Box 5590 SF' Jun 17 15:28:51 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 17 15:28:51 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 17 15:28:51 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 17 15:28:51 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 17 15:28:51 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 17 15:28:51 volumiokeller kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 17 15:28:51 volumiokeller wpa_supplicant[899]: RRM: Ignoring radio measurement request: Not associated Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: carrier acquired Jun 17 15:28:51 volumiokeller wpa_supplicant[899]: wlan0: Associated with b0:f2:08:ec:0b:29 Jun 17 15:28:51 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:f2:08:ec:0b:29 completed [id=0 id_str=] Jun 17 15:28:51 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 17 15:28:51 volumiokeller wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: IAID eb:25:9d:91 Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: carrier lost Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: carrier acquired Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: IAID eb:25:9d:91 Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: soliciting an IPv6 router Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "network"... Jun 17 15:28:51 volumiokeller volumio[488]: info: Refreshing Cached IP Addresses Jun 17 15:28:51 volumiokeller sudo[528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 15:28:51 volumiokeller sudo[528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:51 volumiokeller sudo[528]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:51 volumiokeller sudo[530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 15:28:51 volumiokeller sudo[530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:51 volumiokeller sudo[530]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "services"... Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "alsa_controller"... Jun 17 15:28:51 volumiokeller sudo[539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 17 15:28:51 volumiokeller sudo[539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:51 volumiokeller volumio[488]: warn: Unable to locate the audio output device Allo BOSS. Please configure a valid output device. Jun 17 15:28:51 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "wizard"... Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "networkfs"... Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: rebinding lease of 192.168.178.130 Jun 17 15:28:51 volumiokeller volumio[488]: info: Starting Udev Watcher for removable devices Jun 17 15:28:51 volumiokeller volumio[488]: info: Ignoring mount for partition: boot Jun 17 15:28:51 volumiokeller volumio[488]: info: Ignoring mount for partition: volumio Jun 17 15:28:51 volumiokeller volumio[488]: info: Ignoring mount for partition: volumio_data Jun 17 15:28:51 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "volumio_command_line_client"... Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "upnp"... Jun 17 15:28:51 volumiokeller volumio[488]: info: [1750166931842] Starting Upmpd Daemon Jun 17 15:28:51 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "my_music"... Jun 17 15:28:51 volumiokeller volumio[488]: info: Loading plugin "mpd"... Jun 17 15:28:51 volumiokeller dhcpcd[911]: wlan0: probing address 192.168.178.130/24 Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "upnp_browser"... Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "alarm-clock"... Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "airplay_emulation"... Jun 17 15:28:52 volumiokeller volumio[488]: info: Starting Shairport Sync Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "last_100"... Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "webradio"... Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "i2s_dacs"... Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "volumiodiscovery"... Jun 17 15:28:52 volumiokeller volumio[488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:52 volumiokeller volumio[488]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:52 volumiokeller volumio[488]: *** WARNING *** For more information see Jun 17 15:28:52 volumiokeller volumio[488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:52 volumiokeller volumio[488]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:52 volumiokeller volumio[488]: *** WARNING *** For more information see Jun 17 15:28:52 volumiokeller node[488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:52 volumiokeller node[488]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:52 volumiokeller node[488]: *** WARNING *** For more information see Jun 17 15:28:52 volumiokeller node[488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 15:28:52 volumiokeller node[488]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 15:28:52 volumiokeller node[488]: *** WARNING *** For more information see Jun 17 15:28:52 volumiokeller volumio[488]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 17 15:28:52 volumiokeller volumio[488]: info: Discovery: Started advertising with name: VolumioKeller Jun 17 15:28:52 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 15:28:52 volumiokeller volumio[488]: info: Loading plugin "spop"... Jun 17 15:28:53 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:53] [connect] Successful connection Jun 17 15:28:53 volumiokeller wpa_supplicant[899]: RRM: Ignoring radio measurement request: Not RRM network Jun 17 15:28:53 volumiokeller volumio[488]: info: Loading plugin "squeezelite_mc"... Jun 17 15:28:54 volumiokeller volumio[488]: info: Plugin ytcr is not enabled Jun 17 15:28:54 volumiokeller volumio[488]: info: Loading plugin "outputs"... Jun 17 15:28:54 volumiokeller volumio[488]: info: Loading plugin "albumart"... Jun 17 15:28:54 volumiokeller volumio[488]: info: Plugin example_plugin is not enabled Jun 17 15:28:54 volumiokeller volumio[488]: info: Loading plugin "inputs"... Jun 17 15:28:54 volumiokeller volumio[488]: info: Loading plugin "updater_comm"... Jun 17 15:28:54 volumiokeller volumio[488]: info: Plugin mpdemulation is not enabled Jun 17 15:28:54 volumiokeller volumio[488]: info: Loading plugin "rest_api"... Jun 17 15:28:54 volumiokeller volumio[488]: info: Loading plugin "websocket"... Jun 17 15:28:54 volumiokeller volumio[488]: info: Starting Socket.io Server version 2.3.0 Jun 17 15:28:54 volumiokeller volumio[488]: info: Loading i18n strings for locale de Jun 17 15:28:54 volumiokeller volumio[488]: Updating browse sources language Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:54 volumiokeller volumio[488]: Forking 3 albumart workers Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::initPlayerControls Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:54 volumiokeller volumio[488]: Express server listening on port 3000 Jun 17 15:28:54 volumiokeller volumio[488]: [Metrics] WebUI: 5s 561.67ms Jun 17 15:28:55 volumiokeller sudo[539]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::resetVolumioState Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::getcurrentVolume Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:55 volumiokeller volumio[488]: info: Cannot read play queue from file Jun 17 15:28:55 volumiokeller volumio[488]: info: Volumio Network Manager: Network status updated: 0 Jun 17 15:28:55 volumiokeller volumio-remote-updater[579]: [2025-06-17 15:28:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750166933 101 Jun 17 15:28:55 volumiokeller volumio[488]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::pushState Jun 17 15:28:55 volumiokeller volumio[488]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:55 volumiokeller wpa_supplicant[899]: RRM: Ignoring radio measurement request: Not RRM network Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::updateTrackBlock Jun 17 15:28:55 volumiokeller volumio[488]: info: CorePlayQueue::getTrackBlock Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::pushState Jun 17 15:28:55 volumiokeller volumio[488]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:55 volumiokeller volumio[488]: info: Setting Device type: Raspberry PI Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::setRepeat null single undefined Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::pushState Jun 17 15:28:55 volumiokeller volumio[488]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::setRandom null Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreStateMachine::pushState Jun 17 15:28:55 volumiokeller volumio[488]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:55 volumiokeller volumio[488]: info: Completed loading Core Plugins Jun 17 15:28:55 volumiokeller volumio[488]: info: Preparing to generate the ALSA configuration file Jun 17 15:28:55 volumiokeller volumio[488]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 17 15:28:55 volumiokeller volumio[488]: info: Reading ALSA contributions from plugins. Jun 17 15:28:55 volumiokeller volumio[488]: info: Asound.conf file unchanged, so no further update is needed Jun 17 15:28:55 volumiokeller volumio[488]: info: Output device has changed, restarting MPD Jun 17 15:28:55 volumiokeller volumio[488]: info: Output device has changed, restarting Shairport Sync Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:55 volumiokeller sudo[652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 15:28:55 volumiokeller sudo[652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:55 volumiokeller sudo[653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 15:28:55 volumiokeller sudo[653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:55 volumiokeller sudo[652]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:55 volumiokeller systemd[1]: Stopping Music Player Daemon... Jun 17 15:28:55 volumiokeller systemd[1]: mpd.service: Succeeded. Jun 17 15:28:55 volumiokeller systemd[1]: Stopped Music Player Daemon. Jun 17 15:28:55 volumiokeller volumio[488]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:55 volumiokeller volumio[488]: info: ___________ START PLUGINS ___________ Jun 17 15:28:55 volumiokeller volumio[488]: info: ControllerMpd::onStart: Initializing MPD Jun 17 15:28:55 volumiokeller volumio[488]: info: Creating MPD Configuration file Jun 17 15:28:55 volumiokeller systemd[1]: Starting Music Player Daemon... Jun 17 15:28:55 volumiokeller sudo[662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 15:28:55 volumiokeller sudo[662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:55 volumiokeller sudo[662]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:55 volumiokeller sudo[664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 15:28:55 volumiokeller volumio[488]: info: [1750166935768] CoreMusicLibrary::Adding element Medienserver Jun 17 15:28:55 volumiokeller sudo[664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:55 volumiokeller systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 17 15:28:55 volumiokeller systemd[1]: mpd.service: Succeeded. Jun 17 15:28:55 volumiokeller systemd[1]: Stopped Music Player Daemon. Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:55 volumiokeller systemd[1]: Starting Music Player Daemon... Jun 17 15:28:55 volumiokeller volumio[488]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:55 volumiokeller volumio[488]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:55 volumiokeller sudo[667]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 15:28:55 volumiokeller sudo[667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:55 volumiokeller volumio[488]: info: [1750166935954] CoreMusicLibrary::Adding element Last_100 Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 15:28:55 volumiokeller volumio[488]: info: [1750166935961] CoreMusicLibrary::Adding element Webradio Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 15:28:55 volumiokeller sudo[667]: pam_unix(sudo:session): session closed for user root Jun 17 15:28:55 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:55 volumiokeller volumio[488]: info: Initializing BBC Radios Jun 17 15:28:56 volumiokeller volumio[488]: Starting albumart workers Jun 17 15:28:56 volumiokeller volumio[488]: Starting albumart workers Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:28:56 volumiokeller volumio[488]: Starting albumart workers Jun 17 15:28:56 volumiokeller volumio[488]: info: Creating Spotify config file Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:56 volumiokeller go-librespot[23561]: time="2025-06-17T15:28:56+02:00" level=error msg="did not receive last pong from dealer, 60s passed" Jun 17 15:28:56 volumiokeller volumio[488]: info: [squeezelite_mc] Starting proxy server... Jun 17 15:28:56 volumiokeller volumio[488]: info: Volumio Calling Home Jun 17 15:28:56 volumiokeller volumio[488]: info: [squeezelite_mc] Proxy server started on port 38517 Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 15:28:56 volumiokeller volumio[488]: Unhandled rejection Error: No sockets available, cannot start. Jun 17 15:28:56 volumiokeller volumio[488]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 17 15:28:56 volumiokeller volumio[488]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 17 15:28:56 volumiokeller volumio[488]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 17 15:28:56 volumiokeller volumio[488]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 17 15:28:56 volumiokeller volumio[488]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 17 15:28:56 volumiokeller volumio[488]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 17 15:28:56 volumiokeller volumio[488]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 17 15:28:56 volumiokeller volumio[488]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 17 15:28:56 volumiokeller volumio[488]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 17 15:28:56 volumiokeller volumio[488]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 17 15:28:56 volumiokeller volumio[488]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 17 15:28:56 volumiokeller volumio[488]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 17 15:28:56 volumiokeller volumio[488]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 17 15:28:56 volumiokeller volumio[488]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 17 15:28:56 volumiokeller volumio[488]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 17 15:28:56 volumiokeller volumio[488]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 17 15:28:56 volumiokeller volumio[488]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreStateMachine::pushState Jun 17 15:28:56 volumiokeller volumio[488]: info: CorePlayQueue::getTrack 0 Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::volumioPushState Jun 17 15:28:56 volumiokeller volumio[488]: info: [squeezelite_mc] Server discovery started Jun 17 15:28:56 volumiokeller volumio[488]: info: [squeezelite_mc] Player finder started Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:56 volumiokeller volumio[488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 15:28:56 volumiokeller volumio[488]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 17 15:28:56 volumiokeller volumio[488]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:56 volumiokeller volumio[488]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 17 15:28:56 volumiokeller volumio[488]: at doSend (dgram.js:692:16) Jun 17 15:28:56 volumiokeller volumio[488]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 17 15:28:56 volumiokeller volumio[488]: at afterDns (dgram.js:638:5) Jun 17 15:28:56 volumiokeller volumio[488]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 17 15:28:56 volumiokeller volumio[488]: errno: -101, Jun 17 15:28:56 volumiokeller volumio[488]: code: 'ENETUNREACH', Jun 17 15:28:56 volumiokeller volumio[488]: syscall: 'send', Jun 17 15:28:56 volumiokeller volumio[488]: address: '255.255.255.255', Jun 17 15:28:56 volumiokeller volumio[488]: port: 3483 Jun 17 15:28:56 volumiokeller volumio[488]: } Jun 17 15:28:56 volumiokeller volumio[488]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:28:57 volumiokeller wpa_supplicant[899]: RRM: Ignoring radio measurement request: Not RRM network Jun 17 15:28:57 volumiokeller sudo[733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 15:27 Jun 17 15:28:57 volumiokeller sudo[733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 15:28:57 volumiokeller dhcpcd[911]: wlan0: leased 192.168.178.130 for 864000 seconds Jun 17 15:28:57 volumiokeller avahi-daemon[583]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.130. Jun 17 15:28:57 volumiokeller avahi-daemon[583]: New relevant interface wlan0.IPv4 for mDNS. Jun 17 15:28:57 volumiokeller dhcpcd[911]: wlan0: adding route to 192.168.178.0/24 Jun 17 15:28:57 volumiokeller avahi-daemon[583]: Registering new address record for 192.168.178.130 on wlan0.IPv4. Jun 17 15:28:57 volumiokeller dhcpcd[911]: wlan0: adding default route via 192.168.178.1 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"