-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-09-07 04:03:32 CEST. -- Sep 07 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:01 volumio sudo[16932]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:01 volumio volumio[16805]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:01 volumio volumio[16805]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:02 volumio volumio[16805]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:02 volumio volumio[16805]: info: Reloading queue from file Sep 07 04:02:02 volumio volumio[16805]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:02 volumio volumio[16805]: info: CoreStateMachine::pushState Sep 07 04:02:02 volumio volumio[16805]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:02 volumio volumio[16805]: info: CoreStateMachine::updateTrackBlock Sep 07 04:02:02 volumio volumio[16805]: info: CorePlayQueue::getTrackBlock Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:02 volumio volumio[16805]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:02:02 volumio volumio[16805]: info: CoreStateMachine::pushState Sep 07 04:02:02 volumio volumio[16805]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:02 volumio volumio[16805]: info: CoreStateMachine::setRandom false Sep 07 04:02:02 volumio volumio[16805]: info: CoreStateMachine::pushState Sep 07 04:02:02 volumio volumio[16805]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:02 volumio volumio[16805]: info: Setting Device type: Raspberry PI Sep 07 04:02:02 volumio volumio[16805]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:02 volumio volumio[16805]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:02 volumio volumio[16805]: info: CoreStateMachine::pushState Sep 07 04:02:02 volumio volumio[16805]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:02 volumio volumio[16805]: info: Completed loading Core Plugins Sep 07 04:02:02 volumio volumio[16805]: info: Preparing to generate the ALSA configuration file Sep 07 04:02:02 volumio volumio[16805]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:02:02 volumio volumio[16805]: info: Output device has changed, restarting MPD Sep 07 04:02:02 volumio sudo[16989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:02 volumio sudo[16989]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:02 volumio volumio[16805]: info: ___________ START PLUGINS ___________ Sep 07 04:02:02 volumio sudo[16989]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:02 volumio volumio[16805]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:02:02 volumio volumio[16805]: info: Creating MPD Configuration file Sep 07 04:02:02 volumio sudo[16991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:02 volumio sudo[16991]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 07 04:02:02 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:02:02 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:02 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:02 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:02:02 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:02 volumio volumio[16805]: info: [1757210522259] CoreMusicLibrary::Adding element Last_100 Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:02 volumio sudo[16995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:02 volumio sudo[16995]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:02 volumio volumio[16805]: info: [1757210522265] CoreMusicLibrary::Adding element Webradio Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:02 volumio volumio[16805]: info: Initializing BBC Radios Sep 07 04:02:02 volumio sudo[16998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:02 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:02 volumio sudo[16995]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:02 volumio sudo[16998]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:02 volumio go-librespot[16997]: Librespot-go daemon starting... Sep 07 04:02:02 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:02:02 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:02:02 volumio go-librespot[16997]: time="2025-09-07T04:02:02+02:00" level=info msg="generated new device id: 84e37681655bc95bc93915dd41cac8ae50895bc1" Sep 07 04:02:02 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:02:02 volumio go-librespot[16997]: time="2025-09-07T04:02:02+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 07 04:02:02 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:02:02 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:02:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:02 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:02 volumio volumio[16805]: info: [1757210522423] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:02 volumio volumio[16805]: Cannot find translation for source SoundCloud Sep 07 04:02:02 volumio volumio[16805]: info: Creating Spotify config file Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:02 volumio volumio[16805]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:02 volumio volumio[16805]: info: [1757210522468] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:02 volumio volumio[16805]: Cannot find translation for source SoundCloud Sep 07 04:02:02 volumio volumio[16805]: Cannot find translation for source Radio Paradise Sep 07 04:02:02 volumio sudo[17014]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:02:02 volumio sudo[17014]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:02 volumio sudo[17014]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:02 volumio volumio[16805]: info: Volumio Calling Home Sep 07 04:02:02 volumio sudo[17046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:02:02 volumio sudo[17046]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:02 volumio volumio[16805]: info: [squeezelite_mc] Proxy server started on port 40913 Sep 07 04:02:02 volumio volumio[16805]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:02 volumio volumio[16805]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:02 volumio volumio[16805]: info: MPD Permissions set Sep 07 04:02:02 volumio volumio[16805]: info: MPD Permissions set Sep 07 04:02:03 volumio volumio[16805]: info: Spotify config file written Sep 07 04:02:03 volumio volumio[16805]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 07 04:02:03 volumio sudo[17065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 07 04:02:03 volumio volumio[16805]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:03 volumio volumio[16805]: info: CoreStateMachine::pushState Sep 07 04:02:03 volumio sudo[17065]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:03 volumio volumio[16805]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:03 volumio volumio[16805]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:03 volumio volumio[16805]: info: [squeezelite_mc] Server discovery started Sep 07 04:02:03 volumio volumio[16805]: info: [squeezelite_mc] Player finder started Sep 07 04:02:03 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:03 volumio volumio[16805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:03 volumio volumio[16805]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:02:03 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:03 volumio volumio[16805]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:03 volumio volumio[16805]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:02:03 volumio volumio[16805]: at doSend (dgram.js:692:16) Sep 07 04:02:03 volumio volumio[16805]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:02:03 volumio volumio[16805]: at afterDns (dgram.js:638:5) Sep 07 04:02:03 volumio volumio[16805]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:02:03 volumio volumio[16805]: errno: -101, Sep 07 04:02:03 volumio volumio[16805]: code: 'ENETUNREACH', Sep 07 04:02:03 volumio volumio[16805]: syscall: 'send', Sep 07 04:02:03 volumio volumio[16805]: address: '255.255.255.255', Sep 07 04:02:03 volumio volumio[16805]: port: 3483 Sep 07 04:02:03 volumio volumio[16805]: } Sep 07 04:02:03 volumio volumio[16805]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:03 volumio go-librespot[17068]: Librespot-go daemon starting... Sep 07 04:02:03 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:03 volumio go-librespot[17068]: time="2025-09-07T04:02:03+02:00" level=info msg="generated new device id: 30778e3590ffc6660aec0786b837e988e0dd3ea9" Sep 07 04:02:03 volumio sudo[17065]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:03 volumio go-librespot[17068]: time="2025-09-07T04:02:03+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:03 volumio sudo[17046]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:03 volumio sudo[17088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:01 Sep 07 04:02:03 volumio sudo[17088]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:03 volumio sudo[17088]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:04 volumio sudo[16991]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:04 volumio sudo[16998]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 07 04:02:04 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:04 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:04 volumio mpd[17025]: Sep 07 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 04:02:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 07 04:02:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Sep 07 04:02:04 volumio systemd[1]: Stopped Volumio Backend Module. Sep 07 04:02:04 volumio systemd[1]: Started Volumio Backend Module. Sep 07 04:02:04 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:04 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:04 volumio volumio-remote-updater[554]: [2025-09-07 04:02:04] [info] asio async_connect error: system:111 (Connection refused) Sep 07 04:02:04 volumio volumio-remote-updater[554]: [2025-09-07 04:02:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 07 04:02:04 volumio volumio-remote-updater[554]: [2025-09-07 04:02:04] [error] handle_connect error: Underlying Transport Error Sep 07 04:02:04 volumio systemd[1]: Started Music Player Daemon. Sep 07 04:02:04 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:05 volumio volumio[17116]: cset: --> shielding system active with Sep 07 04:02:05 volumio volumio[17116]: cset: "system" cpuset of CPUSPEC(1-3) with 53 tasks running Sep 07 04:02:05 volumio volumio[17116]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 07 04:02:05 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:06 volumio volumio[17101]: info: ------------------------------------------- Sep 07 04:02:06 volumio volumio[17101]: info: ----- Volumio3 ---- Sep 07 04:02:06 volumio volumio[17101]: info: ------------------------------------------- Sep 07 04:02:06 volumio volumio[17101]: info: ----- System startup ---- Sep 07 04:02:06 volumio volumio[17101]: info: ------------------------------------------- Sep 07 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 07 04:02:06 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:06 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:06 volumio go-librespot[17139]: Librespot-go daemon starting... Sep 07 04:02:06 volumio go-librespot[17139]: time="2025-09-07T04:02:06+02:00" level=info msg="generated new device id: b8951fcac26b61bf3fe71d0fa6f20e494e093e60" Sep 07 04:02:06 volumio go-librespot[17139]: time="2025-09-07T04:02:06+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:06 volumio volumio[17101]: info: MYVOLUMIO Environment detected Sep 07 04:02:06 volumio volumio[17101]: info: Plugin folders cleanup Sep 07 04:02:06 volumio volumio[17101]: info: Scanning into folder /volumio/app/plugins/ Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category audio_interface Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category miscellanea Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category music_service Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category plugins.json Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category system_controller Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category user_interface Sep 07 04:02:06 volumio volumio[17101]: info: Scanning into folder /data/plugins/ Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category music_service Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category system_controller Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category system_hardware Sep 07 04:02:06 volumio volumio[17101]: info: Scanning category user_interface Sep 07 04:02:06 volumio volumio[17101]: info: Plugin folders cleanup completed Sep 07 04:02:06 volumio volumio[17101]: info: ------------------------------------------- Sep 07 04:02:06 volumio volumio[17101]: info: ----- Core plugins startup ---- Sep 07 04:02:06 volumio volumio[17101]: info: ------------------------------------------- Sep 07 04:02:06 volumio volumio[17101]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 04:02:06 volumio volumio[17101]: info: Adding plugin upnp to MyMusic Plugins Sep 07 04:02:06 volumio volumio[17101]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 04:02:06 volumio volumio[17101]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 04:02:06 volumio volumio[17101]: info: Loading plugins from folder /data/plugins/ Sep 07 04:02:06 volumio volumio[17101]: info: Loading plugin "system"... Sep 07 04:02:06 volumio volumio[17101]: info: Loading plugin "appearance"... Sep 07 04:02:07 volumio volumio[17101]: info: Loading plugin "network"... Sep 07 04:02:07 volumio volumio[17101]: info: Refreshing Cached IP Addresses Sep 07 04:02:07 volumio sudo[17152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 04:02:07 volumio sudo[17152]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:07 volumio sudo[17152]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:07 volumio sudo[17154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 04:02:07 volumio sudo[17154]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:07 volumio sudo[17154]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:07 volumio volumio[17101]: info: Loading plugin "services"... Sep 07 04:02:07 volumio volumio[17101]: info: Loading plugin "alsa_controller"... Sep 07 04:02:07 volumio sudo[17163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 04:02:07 volumio sudo[17163]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:07 volumio volumio[17101]: warn: Unable to locate the audio output device PS Audio USB Audio 2.0. Please configure a valid output device. Sep 07 04:02:07 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:07 volumio volumio[17101]: info: Loading plugin "wizard"... Sep 07 04:02:07 volumio volumio[17101]: info: Loading plugin "networkfs"... Sep 07 04:02:08 volumio volumio[17101]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:08 volumio volumio[17101]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:08 volumio volumio[17101]: info: Starting Udev Watcher for removable devices Sep 07 04:02:08 volumio volumio[17101]: info: Ignoring mount for partition: boot Sep 07 04:02:08 volumio volumio[17101]: info: Ignoring mount for partition: volumio Sep 07 04:02:08 volumio volumio[17101]: info: Ignoring mount for partition: volumio_data Sep 07 04:02:08 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "volumio_command_line_client"... Sep 07 04:02:08 volumio volumio[17101]: info: Plugin upnp is not enabled Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "my_music"... Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "mpd"... Sep 07 04:02:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:08 volumio wpa_supplicant[865]: wlan0: Failed to initiate sched scan Sep 07 04:02:08 volumio volumio[17101]: info: Plugin upnp_browser is not enabled Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "alarm-clock"... Sep 07 04:02:08 volumio volumio[17101]: info: Plugin airplay_emulation is not enabled Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "last_100"... Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "webradio"... Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "i2s_dacs"... Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "volumiodiscovery"... Sep 07 04:02:08 volumio volumio[17101]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:08 volumio volumio[17101]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:08 volumio volumio[17101]: *** WARNING *** For more information see Sep 07 04:02:08 volumio node[17101]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:08 volumio volumio[17101]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:08 volumio volumio[17101]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:08 volumio volumio[17101]: *** WARNING *** For more information see Sep 07 04:02:08 volumio node[17101]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:08 volumio node[17101]: *** WARNING *** For more information see Sep 07 04:02:08 volumio node[17101]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:08 volumio node[17101]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:08 volumio node[17101]: *** WARNING *** For more information see Sep 07 04:02:08 volumio volumio[17101]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 04:02:08 volumio volumio[17101]: info: Discovery: Started advertising with name: Volumio Sep 07 04:02:08 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:08 volumio volumio[17101]: info: Loading plugin "soundcloud"... Sep 07 04:02:09 volumio volumio[17101]: info: Loading plugin "spop"... Sep 07 04:02:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 07 04:02:09 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:09 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:09 volumio go-librespot[17179]: Librespot-go daemon starting... Sep 07 04:02:09 volumio go-librespot[17179]: time="2025-09-07T04:02:09+02:00" level=info msg="generated new device id: 126c14e4158a272c6bfc903ecaec8320a5a51062" Sep 07 04:02:09 volumio go-librespot[17179]: time="2025-09-07T04:02:09+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:10 volumio volumio[17101]: info: Loading plugin "squeezelite_mc"... Sep 07 04:02:10 volumio sudo[17163]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:10 volumio volumio[17101]: info: Loading plugin "outputs"... Sep 07 04:02:10 volumio volumio[17101]: info: Loading plugin "albumart"... Sep 07 04:02:10 volumio volumio[17101]: info: Plugin example_plugin is not enabled Sep 07 04:02:10 volumio volumio[17101]: info: Loading plugin "inputs"... Sep 07 04:02:10 volumio volumio[17101]: info: Loading plugin "updater_comm"... Sep 07 04:02:11 volumio volumio[17101]: info: Plugin mpdemulation is not enabled Sep 07 04:02:11 volumio volumio[17101]: info: Loading plugin "rest_api"... Sep 07 04:02:11 volumio volumio[17101]: info: Loading plugin "websocket"... Sep 07 04:02:11 volumio volumio[17101]: info: Starting Socket.io Server version 2.3.0 Sep 07 04:02:11 volumio volumio[17101]: info: Loading plugin "radio_paradise"... Sep 07 04:02:11 volumio volumio[17101]: Forking 3 albumart workers Sep 07 04:02:11 volumio volumio[17101]: info: Applying required configuration parameters for plugin radio_paradise Sep 07 04:02:11 volumio volumio[17101]: info: [1757210531534] [RadioParadise] API delay: 3 Sep 07 04:02:11 volumio volumio[17101]: info: Loading plugin "backup_restore"... Sep 07 04:02:11 volumio volumio[17101]: info: Applying required configuration parameters for plugin backup_restore Sep 07 04:02:11 volumio volumio[17101]: info: Loading plugin "music_services_shield"... Sep 07 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:12 volumio volumio[17101]: info: Applying required configuration parameters for plugin music_services_shield Sep 07 04:02:12 volumio volumio[17101]: info: Loading plugin "Systeminfo"... Sep 07 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:12 volumio volumio[17101]: Starting albumart workers Sep 07 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:12 volumio volumio[17101]: info: Loading i18n strings for locale en Sep 07 04:02:12 volumio volumio[17101]: Updating browse sources language Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:12 volumio volumio[17101]: Starting albumart workers Sep 07 04:02:12 volumio volumio[17101]: Starting albumart workers Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::initPlayerControls Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:12 volumio volumio[17101]: Express server listening on port 3000 Sep 07 04:02:12 volumio volumio[17101]: [Metrics] WebUI: 7s 210.56ms Sep 07 04:02:12 volumio volumio[17101]: info: CoreStateMachine::resetVolumioState Sep 07 04:02:12 volumio volumio[17101]: info: CoreStateMachine::getcurrentVolume Sep 07 04:02:12 volumio volumio[17101]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 07 04:02:12 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:12 volumio volumio[17101]: info: Volumio Network Manager: Network status updated: 0 Sep 07 04:02:12 volumio volumio[17101]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:12 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:12 volumio go-librespot[17218]: Librespot-go daemon starting... Sep 07 04:02:13 volumio go-librespot[17218]: time="2025-09-07T04:02:13+02:00" level=info msg="generated new device id: 7e188d9c0742cc06c5f830e0483a370c693e56b0" Sep 07 04:02:13 volumio go-librespot[17218]: time="2025-09-07T04:02:13+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:13 volumio volumio[17101]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:13 volumio volumio[17101]: info: CoreStateMachine::pushState Sep 07 04:02:13 volumio volumio[17101]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:13 volumio volumio[17101]: info: CoreStateMachine::updateTrackBlock Sep 07 04:02:13 volumio volumio[17101]: info: CorePlayQueue::getTrackBlock Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:13 volumio volumio[17101]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:13 volumio volumio[17101]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:13 volumio volumio[17101]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:13 volumio volumio[17101]: info: Reloading queue from file Sep 07 04:02:13 volumio volumio[17101]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:02:13 volumio volumio[17101]: info: CoreStateMachine::pushState Sep 07 04:02:13 volumio volumio[17101]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:13 volumio volumio[17101]: info: CoreStateMachine::setRandom false Sep 07 04:02:13 volumio volumio[17101]: info: CoreStateMachine::pushState Sep 07 04:02:13 volumio volumio[17101]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:13 volumio volumio[17101]: info: Setting Device type: Raspberry PI Sep 07 04:02:13 volumio volumio[17101]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:13 volumio volumio[17101]: info: CoreStateMachine::pushState Sep 07 04:02:13 volumio volumio[17101]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:13 volumio volumio[17101]: info: Completed loading Core Plugins Sep 07 04:02:13 volumio volumio[17101]: info: Preparing to generate the ALSA configuration file Sep 07 04:02:13 volumio volumio[17101]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:02:13 volumio volumio[17101]: info: Output device has changed, restarting MPD Sep 07 04:02:13 volumio volumio[17101]: info: ___________ START PLUGINS ___________ Sep 07 04:02:13 volumio sudo[17249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:13 volumio sudo[17251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:13 volumio sudo[17251]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:13 volumio volumio[17101]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:02:13 volumio sudo[17249]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:13 volumio volumio[17101]: info: Creating MPD Configuration file Sep 07 04:02:13 volumio sudo[17249]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:13 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:02:13 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:13 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:13 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:02:13 volumio sudo[17260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:13 volumio sudo[17260]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:13 volumio volumio[17101]: info: [1757210533538] CoreMusicLibrary::Adding element Last_100 Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:13 volumio sudo[17260]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:13 volumio volumio[17101]: info: [1757210533544] CoreMusicLibrary::Adding element Webradio Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:13 volumio volumio[17101]: info: Initializing BBC Radios Sep 07 04:02:13 volumio sudo[17266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:13 volumio sudo[17266]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:13 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:02:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:13 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:13 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 07 04:02:13 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:02:13 volumio volumio[17101]: info: [1757210533661] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:02:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:13 volumio volumio[17101]: Cannot find translation for source SoundCloud Sep 07 04:02:13 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:02:13 volumio volumio[17101]: info: Creating Spotify config file Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:13 volumio volumio[17101]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:13 volumio volumio[17101]: info: [1757210533710] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:02:13 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:13 volumio volumio[17101]: Cannot find translation for source SoundCloud Sep 07 04:02:13 volumio volumio[17101]: Cannot find translation for source Radio Paradise Sep 07 04:02:13 volumio sudo[17279]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:02:13 volumio sudo[17279]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:13 volumio sudo[17279]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:13 volumio volumio[17101]: info: Volumio Calling Home Sep 07 04:02:14 volumio volumio[17101]: info: [squeezelite_mc] Proxy server started on port 37747 Sep 07 04:02:14 volumio sudo[17308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:02:14 volumio sudo[17308]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:14 volumio volumio[17101]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:14 volumio volumio[17101]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:14 volumio volumio[17101]: info: MPD Permissions set Sep 07 04:02:14 volumio volumio[17101]: info: MPD Permissions set Sep 07 04:02:14 volumio volumio[17101]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:14 volumio volumio[17101]: info: CoreStateMachine::pushState Sep 07 04:02:14 volumio volumio[17101]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:14 volumio volumio[17101]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:14 volumio volumio[17101]: info: [squeezelite_mc] Server discovery started Sep 07 04:02:14 volumio volumio[17101]: info: [squeezelite_mc] Player finder started Sep 07 04:02:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:14 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:14 volumio volumio[17101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:14 volumio volumio[17101]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:02:14 volumio volumio[17101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:14 volumio volumio[17101]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:02:14 volumio volumio[17101]: at doSend (dgram.js:692:16) Sep 07 04:02:14 volumio volumio[17101]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:02:14 volumio volumio[17101]: at afterDns (dgram.js:638:5) Sep 07 04:02:14 volumio volumio[17101]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:02:14 volumio volumio[17101]: errno: -101, Sep 07 04:02:14 volumio volumio[17101]: code: 'ENETUNREACH', Sep 07 04:02:14 volumio volumio[17101]: syscall: 'send', Sep 07 04:02:14 volumio volumio[17101]: address: '255.255.255.255', Sep 07 04:02:14 volumio volumio[17101]: port: 3483 Sep 07 04:02:14 volumio volumio[17101]: } Sep 07 04:02:14 volumio volumio[17101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:14 volumio sudo[17308]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:14 volumio volumio-remote-updater[554]: [2025-09-07 04:02:14] [connect] Successful connection Sep 07 04:02:14 volumio sudo[17340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:01 Sep 07 04:02:14 volumio sudo[17340]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:14 volumio autossh[26375]: starting ssh (count 10) Sep 07 04:02:14 volumio autossh[26375]: ssh child pid is 17342 Sep 07 04:02:15 volumio volumiossh-tunnel[26373]: ssh: Could not resolve hostname eu4.myvolumio.org: Device or resource busy Sep 07 04:02:15 volumio autossh[26375]: ssh exited with error status 255; restarting ssh Sep 07 04:02:15 volumio sudo[17340]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:15 volumio volumio-remote-updater[554]: [2025-09-07 04:02:15] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Sep 07 04:02:15 volumio volumio-remote-updater[554]: [2025-09-07 04:02:15] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Sep 07 04:02:15 volumio volumio-remote-updater[554]: [2025-09-07 04:02:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 07 04:02:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:15 volumio sudo[17251]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:15 volumio sudo[17266]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 07 04:02:15 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:15 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:15 volumio mpd[17293]: Sep 07 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 04:02:15 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 07 04:02:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Sep 07 04:02:15 volumio systemd[1]: Stopped Volumio Backend Module. Sep 07 04:02:15 volumio systemd[1]: Started Volumio Backend Module. Sep 07 04:02:15 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:15 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:15 volumio systemd[1]: Started Music Player Daemon. Sep 07 04:02:16 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 07 04:02:16 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:16 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:16 volumio go-librespot[17371]: Librespot-go daemon starting... Sep 07 04:02:16 volumio go-librespot[17371]: time="2025-09-07T04:02:16+02:00" level=info msg="generated new device id: 67de6dd4334d1596ad9f4fd0faca83b0f9e08e6d" Sep 07 04:02:16 volumio go-librespot[17371]: time="2025-09-07T04:02:16+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:16 volumio volumio[17369]: cset: --> shielding system active with Sep 07 04:02:16 volumio volumio[17369]: cset: "system" cpuset of CPUSPEC(1-3) with 53 tasks running Sep 07 04:02:16 volumio volumio[17369]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 07 04:02:16 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:17 volumio volumio[17354]: info: ------------------------------------------- Sep 07 04:02:17 volumio volumio[17354]: info: ----- Volumio3 ---- Sep 07 04:02:17 volumio volumio[17354]: info: ------------------------------------------- Sep 07 04:02:17 volumio volumio[17354]: info: ----- System startup ---- Sep 07 04:02:17 volumio volumio[17354]: info: ------------------------------------------- Sep 07 04:02:17 volumio volumio[17354]: info: MYVOLUMIO Environment detected Sep 07 04:02:17 volumio volumio[17354]: info: Plugin folders cleanup Sep 07 04:02:17 volumio volumio[17354]: info: Scanning into folder /volumio/app/plugins/ Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category audio_interface Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category miscellanea Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category music_service Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category plugins.json Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category system_controller Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category user_interface Sep 07 04:02:17 volumio volumio[17354]: info: Scanning into folder /data/plugins/ Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category music_service Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category system_controller Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category system_hardware Sep 07 04:02:17 volumio volumio[17354]: info: Scanning category user_interface Sep 07 04:02:17 volumio volumio[17354]: info: Plugin folders cleanup completed Sep 07 04:02:17 volumio volumio[17354]: info: ------------------------------------------- Sep 07 04:02:17 volumio volumio[17354]: info: ----- Core plugins startup ---- Sep 07 04:02:17 volumio volumio[17354]: info: ------------------------------------------- Sep 07 04:02:17 volumio volumio[17354]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 04:02:17 volumio volumio[17354]: info: Adding plugin upnp to MyMusic Plugins Sep 07 04:02:17 volumio volumio[17354]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 04:02:17 volumio volumio[17354]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 04:02:17 volumio volumio[17354]: info: Loading plugins from folder /data/plugins/ Sep 07 04:02:17 volumio volumio[17354]: info: Loading plugin "system"... Sep 07 04:02:17 volumio volumio[17354]: info: Loading plugin "appearance"... Sep 07 04:02:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:18 volumio volumio[17354]: info: Loading plugin "network"... Sep 07 04:02:18 volumio volumio[17354]: info: Refreshing Cached IP Addresses Sep 07 04:02:18 volumio sudo[17408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 04:02:18 volumio sudo[17408]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:18 volumio sudo[17410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 04:02:18 volumio sudo[17410]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:18 volumio sudo[17408]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:18 volumio sudo[17410]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:18 volumio volumio[17354]: info: Loading plugin "services"... Sep 07 04:02:18 volumio volumio[17354]: info: Loading plugin "alsa_controller"... Sep 07 04:02:18 volumio sudo[17415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 04:02:18 volumio sudo[17415]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:18 volumio volumio[17354]: warn: Unable to locate the audio output device PS Audio USB Audio 2.0. Please configure a valid output device. Sep 07 04:02:18 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:18 volumio volumio[17354]: info: Loading plugin "wizard"... Sep 07 04:02:18 volumio volumio[17354]: info: Loading plugin "networkfs"... Sep 07 04:02:18 volumio volumio[17354]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:18 volumio volumio[17354]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:18 volumio volumio[17354]: info: Starting Udev Watcher for removable devices Sep 07 04:02:19 volumio volumio[17354]: info: Ignoring mount for partition: boot Sep 07 04:02:19 volumio volumio[17354]: info: Ignoring mount for partition: volumio Sep 07 04:02:19 volumio volumio[17354]: info: Ignoring mount for partition: volumio_data Sep 07 04:02:19 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "volumio_command_line_client"... Sep 07 04:02:19 volumio volumio[17354]: info: Plugin upnp is not enabled Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "my_music"... Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "mpd"... Sep 07 04:02:19 volumio volumio[17354]: info: Plugin upnp_browser is not enabled Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "alarm-clock"... Sep 07 04:02:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 07 04:02:19 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:19 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:19 volumio go-librespot[17432]: Librespot-go daemon starting... Sep 07 04:02:19 volumio volumio[17354]: info: Plugin airplay_emulation is not enabled Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "last_100"... Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "webradio"... Sep 07 04:02:19 volumio go-librespot[17432]: time="2025-09-07T04:02:19+02:00" level=info msg="generated new device id: f985206407fd5fa8462c96d724065cea3b19cada" Sep 07 04:02:19 volumio go-librespot[17432]: time="2025-09-07T04:02:19+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "i2s_dacs"... Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "volumiodiscovery"... Sep 07 04:02:19 volumio volumio[17354]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:19 volumio volumio[17354]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:19 volumio volumio[17354]: *** WARNING *** For more information see Sep 07 04:02:19 volumio node[17354]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:19 volumio volumio[17354]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:19 volumio volumio[17354]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:19 volumio volumio[17354]: *** WARNING *** For more information see Sep 07 04:02:19 volumio node[17354]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:19 volumio node[17354]: *** WARNING *** For more information see Sep 07 04:02:19 volumio node[17354]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:19 volumio node[17354]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:19 volumio node[17354]: *** WARNING *** For more information see Sep 07 04:02:19 volumio volumio[17354]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 04:02:19 volumio volumio[17354]: info: Discovery: Started advertising with name: Volumio Sep 07 04:02:19 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:19 volumio volumio[17354]: info: Loading plugin "soundcloud"... Sep 07 04:02:20 volumio volumio[17354]: info: Loading plugin "spop"... Sep 07 04:02:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:20 volumio wpa_supplicant[865]: wlan0: Failed to initiate sched scan Sep 07 04:02:21 volumio volumio[17354]: info: Loading plugin "squeezelite_mc"... Sep 07 04:02:21 volumio volumio[17354]: info: Loading plugin "outputs"... Sep 07 04:02:21 volumio volumio[17354]: info: Loading plugin "albumart"... Sep 07 04:02:21 volumio volumio[17354]: info: Plugin example_plugin is not enabled Sep 07 04:02:21 volumio volumio[17354]: info: Loading plugin "inputs"... Sep 07 04:02:21 volumio volumio[17354]: info: Loading plugin "updater_comm"... Sep 07 04:02:22 volumio volumio[17354]: info: Plugin mpdemulation is not enabled Sep 07 04:02:22 volumio volumio[17354]: info: Loading plugin "rest_api"... Sep 07 04:02:22 volumio volumio[17354]: info: Loading plugin "websocket"... Sep 07 04:02:22 volumio volumio[17354]: info: Starting Socket.io Server version 2.3.0 Sep 07 04:02:22 volumio volumio[17354]: info: Loading plugin "radio_paradise"... Sep 07 04:02:22 volumio volumio[17354]: Forking 3 albumart workers Sep 07 04:02:22 volumio volumio[17354]: info: Applying required configuration parameters for plugin radio_paradise Sep 07 04:02:22 volumio volumio[17354]: info: [1757210542400] [RadioParadise] API delay: 3 Sep 07 04:02:22 volumio volumio[17354]: info: Loading plugin "backup_restore"... Sep 07 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 07 04:02:22 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:22 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:22 volumio go-librespot[17470]: Librespot-go daemon starting... Sep 07 04:02:22 volumio go-librespot[17470]: time="2025-09-07T04:02:22+02:00" level=info msg="generated new device id: b38c3c97bd6395df292ecc0db7ea833efa155815" Sep 07 04:02:22 volumio go-librespot[17470]: time="2025-09-07T04:02:22+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:22 volumio volumio[17354]: info: Applying required configuration parameters for plugin backup_restore Sep 07 04:02:22 volumio volumio[17354]: info: Loading plugin "music_services_shield"... Sep 07 04:02:22 volumio sudo[17415]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:23 volumio volumio[17354]: info: Applying required configuration parameters for plugin music_services_shield Sep 07 04:02:23 volumio volumio[17354]: info: Loading plugin "Systeminfo"... Sep 07 04:02:23 volumio volumio[17354]: Starting albumart workers Sep 07 04:02:23 volumio volumio[17354]: Starting albumart workers Sep 07 04:02:23 volumio volumio[17354]: Starting albumart workers Sep 07 04:02:23 volumio volumio[17354]: info: Loading i18n strings for locale en Sep 07 04:02:23 volumio volumio[17354]: Updating browse sources language Sep 07 04:02:23 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::initPlayerControls Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: Express server listening on port 3000 Sep 07 04:02:24 volumio volumio[17354]: [Metrics] WebUI: 7s 529.58ms Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::resetVolumioState Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::getcurrentVolume Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:24 volumio volumio[17354]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:24 volumio volumio[17354]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:24 volumio volumio[17354]: info: Volumio Network Manager: Network status updated: 0 Sep 07 04:02:24 volumio volumio[17354]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:24 volumio volumio[17354]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::pushState Sep 07 04:02:24 volumio volumio[17354]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::updateTrackBlock Sep 07 04:02:24 volumio volumio[17354]: info: CorePlayQueue::getTrackBlock Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: Reloading queue from file Sep 07 04:02:24 volumio volumio[17354]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::pushState Sep 07 04:02:24 volumio volumio[17354]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::setRandom false Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::pushState Sep 07 04:02:24 volumio volumio[17354]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:24 volumio volumio[17354]: info: Setting Device type: Raspberry PI Sep 07 04:02:24 volumio volumio[17354]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:24 volumio volumio[17354]: info: CoreStateMachine::pushState Sep 07 04:02:24 volumio volumio[17354]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:24 volumio volumio[17354]: info: Completed loading Core Plugins Sep 07 04:02:24 volumio volumio[17354]: info: Preparing to generate the ALSA configuration file Sep 07 04:02:24 volumio volumio[17354]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:02:24 volumio volumio[17354]: info: Output device has changed, restarting MPD Sep 07 04:02:24 volumio sudo[17513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:24 volumio volumio[17354]: info: ___________ START PLUGINS ___________ Sep 07 04:02:24 volumio sudo[17513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:24 volumio sudo[17513]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:24 volumio volumio[17354]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:02:24 volumio volumio[17354]: info: Creating MPD Configuration file Sep 07 04:02:24 volumio sudo[17515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:24 volumio sudo[17515]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:24 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:02:24 volumio sudo[17519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:24 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:24 volumio sudo[17519]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:24 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:24 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:02:24 volumio sudo[17519]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:24 volumio volumio[17354]: info: [1757210544574] CoreMusicLibrary::Adding element Last_100 Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:24 volumio volumio[17354]: info: [1757210544579] CoreMusicLibrary::Adding element Webradio Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:24 volumio sudo[17521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:24 volumio sudo[17521]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:24 volumio volumio[17354]: info: Initializing BBC Radios Sep 07 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:24 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:02:24 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:24 volumio volumio[17354]: info: [1757210544675] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:24 volumio volumio[17354]: Cannot find translation for source SoundCloud Sep 07 04:02:24 volumio volumio[17354]: info: Creating Spotify config file Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:24 volumio volumio[17354]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:24 volumio volumio[17354]: info: [1757210544718] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:02:24 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:24 volumio volumio[17354]: Cannot find translation for source SoundCloud Sep 07 04:02:24 volumio volumio[17354]: Cannot find translation for source Radio Paradise Sep 07 04:02:24 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:02:24 volumio sudo[17531]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:02:24 volumio sudo[17531]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:24 volumio sudo[17531]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:24 volumio volumio[17354]: info: Volumio Calling Home Sep 07 04:02:24 volumio sudo[17563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:02:25 volumio sudo[17563]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:25 volumio volumio[17354]: info: [squeezelite_mc] Proxy server started on port 44027 Sep 07 04:02:25 volumio volumio[17354]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:25 volumio volumio[17354]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:25 volumio volumio-remote-updater[554]: [2025-09-07 04:02:25] [connect] Successful connection Sep 07 04:02:25 volumio volumio[17354]: info: MPD Permissions set Sep 07 04:02:25 volumio volumio[17354]: info: MPD Permissions set Sep 07 04:02:25 volumio volumio[17354]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:25 volumio volumio[17354]: info: CoreStateMachine::pushState Sep 07 04:02:25 volumio volumio[17354]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:25 volumio volumio[17354]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:25 volumio volumio[17354]: info: [squeezelite_mc] Server discovery started Sep 07 04:02:25 volumio volumio[17354]: info: [squeezelite_mc] Player finder started Sep 07 04:02:25 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:25 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:25 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:25 volumio volumio[17354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:25 volumio volumio[17354]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:02:25 volumio volumio[17354]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:25 volumio volumio[17354]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:02:25 volumio volumio[17354]: at doSend (dgram.js:692:16) Sep 07 04:02:25 volumio volumio[17354]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:02:25 volumio volumio[17354]: at afterDns (dgram.js:638:5) Sep 07 04:02:25 volumio volumio[17354]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:02:25 volumio volumio[17354]: errno: -101, Sep 07 04:02:25 volumio volumio[17354]: code: 'ENETUNREACH', Sep 07 04:02:25 volumio volumio[17354]: syscall: 'send', Sep 07 04:02:25 volumio volumio[17354]: address: '255.255.255.255', Sep 07 04:02:25 volumio volumio[17354]: port: 3483 Sep 07 04:02:25 volumio volumio[17354]: } Sep 07 04:02:25 volumio volumio[17354]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:25 volumio sudo[17563]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 07 04:02:25 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:25 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:25 volumio go-librespot[17594]: Librespot-go daemon starting... Sep 07 04:02:25 volumio sudo[17596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:01 Sep 07 04:02:25 volumio sudo[17596]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:25 volumio go-librespot[17594]: time="2025-09-07T04:02:25+02:00" level=info msg="generated new device id: 081494b353d263ae3f37dc4ab19dc5ad246c3432" Sep 07 04:02:25 volumio go-librespot[17594]: time="2025-09-07T04:02:25+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:26 volumio sudo[17596]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:26 volumio volumio-remote-updater[554]: [2025-09-07 04:02:26] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Sep 07 04:02:26 volumio volumio-remote-updater[554]: [2025-09-07 04:02:26] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Sep 07 04:02:26 volumio volumio-remote-updater[554]: [2025-09-07 04:02:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 07 04:02:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:26 volumio sudo[17515]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:26 volumio sudo[17521]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 07 04:02:26 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:26 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 07 04:02:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Sep 07 04:02:26 volumio systemd[1]: Stopped Volumio Backend Module. Sep 07 04:02:26 volumio systemd[1]: Started Volumio Backend Module. Sep 07 04:02:26 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:26 volumio mpd[17557]: Sep 07 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 04:02:27 volumio systemd[1]: Started Music Player Daemon. Sep 07 04:02:27 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:27 volumio volumio[17662]: cset: --> shielding system active with Sep 07 04:02:27 volumio volumio[17662]: cset: "system" cpuset of CPUSPEC(1-3) with 53 tasks running Sep 07 04:02:27 volumio volumio[17662]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 07 04:02:27 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:28 volumio volumio[17613]: info: ------------------------------------------- Sep 07 04:02:28 volumio volumio[17613]: info: ----- Volumio3 ---- Sep 07 04:02:28 volumio volumio[17613]: info: ------------------------------------------- Sep 07 04:02:28 volumio volumio[17613]: info: ----- System startup ---- Sep 07 04:02:28 volumio volumio[17613]: info: ------------------------------------------- Sep 07 04:02:28 volumio volumio[17613]: info: MYVOLUMIO Environment detected Sep 07 04:02:28 volumio volumio[17613]: info: Plugin folders cleanup Sep 07 04:02:28 volumio volumio[17613]: info: Scanning into folder /volumio/app/plugins/ Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category audio_interface Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category miscellanea Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category music_service Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category plugins.json Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category system_controller Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category user_interface Sep 07 04:02:28 volumio volumio[17613]: info: Scanning into folder /data/plugins/ Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category music_service Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category system_controller Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category system_hardware Sep 07 04:02:28 volumio volumio[17613]: info: Scanning category user_interface Sep 07 04:02:28 volumio volumio[17613]: info: Plugin folders cleanup completed Sep 07 04:02:28 volumio volumio[17613]: info: ------------------------------------------- Sep 07 04:02:28 volumio volumio[17613]: info: ----- Core plugins startup ---- Sep 07 04:02:28 volumio volumio[17613]: info: ------------------------------------------- Sep 07 04:02:28 volumio volumio[17613]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 04:02:28 volumio volumio[17613]: info: Adding plugin upnp to MyMusic Plugins Sep 07 04:02:28 volumio volumio[17613]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 04:02:28 volumio volumio[17613]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 04:02:28 volumio volumio[17613]: info: Loading plugins from folder /data/plugins/ Sep 07 04:02:28 volumio volumio[17613]: info: Loading plugin "system"... Sep 07 04:02:28 volumio volumio[17613]: info: Loading plugin "appearance"... Sep 07 04:02:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Sep 07 04:02:29 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:29 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:29 volumio go-librespot[17690]: Librespot-go daemon starting... Sep 07 04:02:29 volumio go-librespot[17690]: time="2025-09-07T04:02:29+02:00" level=info msg="generated new device id: 940ead9d40ae333cee27fbc281455aff82772264" Sep 07 04:02:29 volumio go-librespot[17690]: time="2025-09-07T04:02:29+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:29 volumio volumio[17613]: info: Loading plugin "network"... Sep 07 04:02:29 volumio volumio[17613]: info: Refreshing Cached IP Addresses Sep 07 04:02:29 volumio sudo[17698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 04:02:29 volumio sudo[17698]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:29 volumio sudo[17700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 04:02:29 volumio sudo[17700]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:29 volumio sudo[17698]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:29 volumio sudo[17700]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:29 volumio volumio[17613]: info: Loading plugin "services"... Sep 07 04:02:29 volumio volumio[17613]: info: Loading plugin "alsa_controller"... Sep 07 04:02:29 volumio sudo[17708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 04:02:29 volumio sudo[17708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:29 volumio volumio[17613]: warn: Unable to locate the audio output device PS Audio USB Audio 2.0. Please configure a valid output device. Sep 07 04:02:29 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:29 volumio volumio[17613]: info: Loading plugin "wizard"... Sep 07 04:02:29 volumio volumio[17613]: info: Loading plugin "networkfs"... Sep 07 04:02:29 volumio volumio[17613]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:29 volumio volumio[17613]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:29 volumio volumio[17613]: info: Starting Udev Watcher for removable devices Sep 07 04:02:30 volumio volumio[17613]: info: Ignoring mount for partition: boot Sep 07 04:02:30 volumio volumio[17613]: info: Ignoring mount for partition: volumio Sep 07 04:02:30 volumio volumio[17613]: info: Ignoring mount for partition: volumio_data Sep 07 04:02:30 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "volumio_command_line_client"... Sep 07 04:02:30 volumio volumio[17613]: info: Plugin upnp is not enabled Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "my_music"... Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "mpd"... Sep 07 04:02:30 volumio volumio[17613]: info: Plugin upnp_browser is not enabled Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "alarm-clock"... Sep 07 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:30 volumio volumio[17613]: info: Plugin airplay_emulation is not enabled Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "last_100"... Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "webradio"... Sep 07 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "i2s_dacs"... Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "volumiodiscovery"... Sep 07 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:30 volumio volumio[17613]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:30 volumio volumio[17613]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:30 volumio volumio[17613]: *** WARNING *** For more information see Sep 07 04:02:30 volumio node[17613]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:30 volumio volumio[17613]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:30 volumio volumio[17613]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:30 volumio volumio[17613]: *** WARNING *** For more information see Sep 07 04:02:30 volumio node[17613]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:30 volumio node[17613]: *** WARNING *** For more information see Sep 07 04:02:30 volumio node[17613]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:30 volumio node[17613]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:30 volumio node[17613]: *** WARNING *** For more information see Sep 07 04:02:30 volumio volumio[17613]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 04:02:30 volumio volumio[17613]: info: Discovery: Started advertising with name: Volumio Sep 07 04:02:30 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:30 volumio volumio[17613]: info: Loading plugin "soundcloud"... Sep 07 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:31 volumio volumio[17613]: info: Loading plugin "spop"... Sep 07 04:02:32 volumio volumio[17613]: info: Loading plugin "squeezelite_mc"... Sep 07 04:02:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Sep 07 04:02:32 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:32 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:32 volumio go-librespot[17724]: Librespot-go daemon starting... Sep 07 04:02:32 volumio go-librespot[17724]: time="2025-09-07T04:02:32+02:00" level=info msg="generated new device id: 44b083da0840b966cec8ebbb6e961d4cfa960321" Sep 07 04:02:32 volumio go-librespot[17724]: time="2025-09-07T04:02:32+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:32 volumio wpa_supplicant[865]: wlan0: Failed to initiate sched scan Sep 07 04:02:32 volumio volumio[17613]: info: Loading plugin "outputs"... Sep 07 04:02:32 volumio volumio[17613]: info: Loading plugin "albumart"... Sep 07 04:02:32 volumio volumio[17613]: info: Plugin example_plugin is not enabled Sep 07 04:02:32 volumio volumio[17613]: info: Loading plugin "inputs"... Sep 07 04:02:32 volumio volumio[17613]: info: Loading plugin "updater_comm"... Sep 07 04:02:33 volumio volumio[17613]: info: Plugin mpdemulation is not enabled Sep 07 04:02:33 volumio volumio[17613]: info: Loading plugin "rest_api"... Sep 07 04:02:33 volumio volumio[17613]: info: Loading plugin "websocket"... Sep 07 04:02:33 volumio volumio[17613]: info: Starting Socket.io Server version 2.3.0 Sep 07 04:02:33 volumio volumio[17613]: info: Loading plugin "radio_paradise"... Sep 07 04:02:33 volumio volumio[17613]: Forking 3 albumart workers Sep 07 04:02:33 volumio volumio[17613]: info: Applying required configuration parameters for plugin radio_paradise Sep 07 04:02:33 volumio volumio[17613]: info: [1757210553337] [RadioParadise] API delay: 3 Sep 07 04:02:33 volumio volumio[17613]: info: Loading plugin "backup_restore"... Sep 07 04:02:33 volumio volumio[17613]: info: Applying required configuration parameters for plugin backup_restore Sep 07 04:02:33 volumio volumio[17613]: info: Loading plugin "music_services_shield"... Sep 07 04:02:34 volumio volumio[17613]: Starting albumart workers Sep 07 04:02:34 volumio volumio[17613]: Starting albumart workers Sep 07 04:02:34 volumio volumio[17613]: info: Applying required configuration parameters for plugin music_services_shield Sep 07 04:02:34 volumio volumio[17613]: info: Loading plugin "Systeminfo"... Sep 07 04:02:34 volumio volumio[17613]: Starting albumart workers Sep 07 04:02:34 volumio volumio[17613]: info: Loading i18n strings for locale en Sep 07 04:02:34 volumio volumio[17613]: Updating browse sources language Sep 07 04:02:34 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:35 volumio sudo[17708]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::initPlayerControls Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: Express server listening on port 3000 Sep 07 04:02:35 volumio volumio[17613]: [Metrics] WebUI: 7s 606.23ms Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::resetVolumioState Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::getcurrentVolume Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:35 volumio volumio[17613]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:35 volumio volumio[17613]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:35 volumio volumio[17613]: info: Volumio Network Manager: Network status updated: 0 Sep 07 04:02:35 volumio volumio[17613]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:35 volumio volumio[17613]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::pushState Sep 07 04:02:35 volumio volumio[17613]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::updateTrackBlock Sep 07 04:02:35 volumio volumio[17613]: info: CorePlayQueue::getTrackBlock Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: Reloading queue from file Sep 07 04:02:35 volumio volumio[17613]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::pushState Sep 07 04:02:35 volumio volumio[17613]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::setRandom false Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::pushState Sep 07 04:02:35 volumio volumio[17613]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:35 volumio volumio[17613]: info: Setting Device type: Raspberry PI Sep 07 04:02:35 volumio volumio[17613]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:35 volumio volumio[17613]: info: CoreStateMachine::pushState Sep 07 04:02:35 volumio volumio[17613]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:35 volumio volumio[17613]: info: Completed loading Core Plugins Sep 07 04:02:35 volumio volumio[17613]: info: Preparing to generate the ALSA configuration file Sep 07 04:02:35 volumio volumio[17613]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:02:35 volumio volumio[17613]: info: Output device has changed, restarting MPD Sep 07 04:02:35 volumio sudo[17796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:35 volumio sudo[17796]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:35 volumio volumio[17613]: info: ___________ START PLUGINS ___________ Sep 07 04:02:35 volumio sudo[17796]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:35 volumio volumio[17613]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:02:35 volumio volumio[17613]: info: Creating MPD Configuration file Sep 07 04:02:35 volumio sudo[17798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:35 volumio sudo[17798]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Sep 07 04:02:35 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:35 volumio sudo[17802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:35 volumio sudo[17802]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:35 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:35 volumio volumio[17613]: info: [1757210555585] CoreMusicLibrary::Adding element Last_100 Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:35 volumio volumio[17613]: info: [1757210555588] CoreMusicLibrary::Adding element Webradio Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:35 volumio volumio[17613]: info: Initializing BBC Radios Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:35 volumio volumio[17613]: info: [1757210555672] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:35 volumio volumio[17613]: Cannot find translation for source SoundCloud Sep 07 04:02:35 volumio sudo[17802]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:35 volumio sudo[17806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:35 volumio sudo[17806]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:35 volumio go-librespot[17804]: Librespot-go daemon starting... Sep 07 04:02:35 volumio volumio[17613]: info: Creating Spotify config file Sep 07 04:02:35 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:35 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:35 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:35 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:02:35 volumio go-librespot[17804]: time="2025-09-07T04:02:35+02:00" level=info msg="generated new device id: 17fd2aa7aca937937ae596b0380b256d7eb97f94" Sep 07 04:02:35 volumio go-librespot[17804]: time="2025-09-07T04:02:35+02:00" level=fatal msg="unknown credentials: " Sep 07 04:02:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:35 volumio volumio[17613]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:35 volumio volumio[17613]: info: [1757210555741] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:02:35 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:35 volumio volumio[17613]: Cannot find translation for source SoundCloud Sep 07 04:02:35 volumio volumio[17613]: Cannot find translation for source Radio Paradise Sep 07 04:02:35 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:02:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:02:35 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:02:35 volumio volumio[17613]: info: Volumio Calling Home Sep 07 04:02:35 volumio sudo[17830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:02:35 volumio sudo[17830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:36 volumio sudo[17830]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:36 volumio sudo[17851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:02:36 volumio sudo[17851]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:36 volumio volumio[17613]: info: [squeezelite_mc] Proxy server started on port 44077 Sep 07 04:02:36 volumio volumio-remote-updater[554]: [2025-09-07 04:02:36] [connect] Successful connection Sep 07 04:02:36 volumio volumio[17613]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:36 volumio volumio[17613]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:36 volumio volumio[17613]: info: MPD Permissions set Sep 07 04:02:36 volumio volumio[17613]: info: MPD Permissions set Sep 07 04:02:36 volumio volumio-remote-updater[554]: [2025-09-07 04:02:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757210556 101 Sep 07 04:02:36 volumio volumio[17613]: 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 Sep 07 04:02:36 volumio volumio[17613]: info: Spotify config file written Sep 07 04:02:36 volumio sudo[17872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 07 04:02:36 volumio sudo[17872]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:36 volumio volumio[17613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 07 04:02:36 volumio volumio[17613]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:36 volumio volumio[17613]: info: CoreStateMachine::pushState Sep 07 04:02:36 volumio volumio[17613]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:36 volumio volumio[17613]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:36 volumio volumio[17613]: info: [squeezelite_mc] Server discovery started Sep 07 04:02:36 volumio volumio[17613]: info: [squeezelite_mc] Player finder started Sep 07 04:02:36 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:36 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:36 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:36 volumio volumio[17613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:36 volumio volumio[17613]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:02:36 volumio volumio[17613]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:36 volumio volumio[17613]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:02:36 volumio volumio[17613]: at doSend (dgram.js:692:16) Sep 07 04:02:36 volumio volumio[17613]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:02:36 volumio volumio[17613]: at afterDns (dgram.js:638:5) Sep 07 04:02:36 volumio volumio[17613]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:02:36 volumio volumio[17613]: errno: -101, Sep 07 04:02:36 volumio volumio[17613]: code: 'ENETUNREACH', Sep 07 04:02:36 volumio volumio[17613]: syscall: 'send', Sep 07 04:02:36 volumio volumio[17613]: address: '255.255.255.255', Sep 07 04:02:36 volumio volumio[17613]: port: 3483 Sep 07 04:02:36 volumio volumio[17613]: } Sep 07 04:02:36 volumio volumio[17613]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:36 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:36 volumio go-librespot[17874]: Librespot-go daemon starting... Sep 07 04:02:36 volumio sudo[17872]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:36 volumio go-librespot[17874]: time="2025-09-07T04:02:36+02:00" level=info msg="generated new device id: 9d40f60788b14c63a370210e49c8912ec3a77ade" Sep 07 04:02:36 volumio go-librespot[17874]: time="2025-09-07T04:02:36+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:36 volumio sudo[17851]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:37 volumio sudo[17895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:01 Sep 07 04:02:37 volumio sudo[17895]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:37 volumio sudo[17895]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:37 volumio volumio-remote-updater[554]: [2025-09-07 04:02:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 07 04:02:37 volumio volumio-remote-updater[554]: [2025-09-07 04:02:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 07 04:02:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:37 volumio sudo[17806]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:37 volumio sudo[17798]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 07 04:02:37 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:37 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 07 04:02:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Sep 07 04:02:37 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:37 volumio mpd[17856]: Sep 07 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 04:02:37 volumio systemd[1]: Stopped Volumio Backend Module. Sep 07 04:02:37 volumio systemd[1]: Started Volumio Backend Module. Sep 07 04:02:37 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:38 volumio systemd[1]: Started Music Player Daemon. Sep 07 04:02:38 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:38 volumio volumio[17925]: cset: --> shielding system active with Sep 07 04:02:38 volumio volumio[17925]: cset: "system" cpuset of CPUSPEC(1-3) with 53 tasks running Sep 07 04:02:38 volumio volumio[17925]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 07 04:02:38 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:39 volumio volumio[17909]: info: ------------------------------------------- Sep 07 04:02:39 volumio volumio[17909]: info: ----- Volumio3 ---- Sep 07 04:02:39 volumio volumio[17909]: info: ------------------------------------------- Sep 07 04:02:39 volumio volumio[17909]: info: ----- System startup ---- Sep 07 04:02:39 volumio volumio[17909]: info: ------------------------------------------- Sep 07 04:02:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Sep 07 04:02:39 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:39 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:39 volumio go-librespot[17949]: Librespot-go daemon starting... Sep 07 04:02:39 volumio go-librespot[17949]: time="2025-09-07T04:02:39+02:00" level=info msg="generated new device id: 9d2f551d6a4f1794ab2c21e05be5e910599199cc" Sep 07 04:02:39 volumio go-librespot[17949]: time="2025-09-07T04:02:39+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:39 volumio volumio[17909]: info: MYVOLUMIO Environment detected Sep 07 04:02:39 volumio volumio[17909]: info: Plugin folders cleanup Sep 07 04:02:39 volumio volumio[17909]: info: Scanning into folder /volumio/app/plugins/ Sep 07 04:02:39 volumio volumio[17909]: info: Scanning category audio_interface Sep 07 04:02:39 volumio volumio[17909]: info: Scanning category miscellanea Sep 07 04:02:39 volumio volumio[17909]: info: Scanning category music_service Sep 07 04:02:39 volumio volumio[17909]: info: Scanning category plugins.json Sep 07 04:02:39 volumio volumio[17909]: info: Scanning category system_controller Sep 07 04:02:39 volumio volumio[17909]: info: Scanning category user_interface Sep 07 04:02:40 volumio volumio[17909]: info: Scanning into folder /data/plugins/ Sep 07 04:02:40 volumio volumio[17909]: info: Scanning category music_service Sep 07 04:02:40 volumio volumio[17909]: info: Scanning category system_controller Sep 07 04:02:40 volumio volumio[17909]: info: Scanning category system_hardware Sep 07 04:02:40 volumio volumio[17909]: info: Scanning category user_interface Sep 07 04:02:40 volumio volumio[17909]: info: Plugin folders cleanup completed Sep 07 04:02:40 volumio volumio[17909]: info: ------------------------------------------- Sep 07 04:02:40 volumio volumio[17909]: info: ----- Core plugins startup ---- Sep 07 04:02:40 volumio volumio[17909]: info: ------------------------------------------- Sep 07 04:02:40 volumio volumio[17909]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 04:02:40 volumio volumio[17909]: info: Adding plugin upnp to MyMusic Plugins Sep 07 04:02:40 volumio volumio[17909]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 04:02:40 volumio volumio[17909]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 04:02:40 volumio volumio[17909]: info: Loading plugins from folder /data/plugins/ Sep 07 04:02:40 volumio volumio[17909]: info: Loading plugin "system"... Sep 07 04:02:40 volumio volumio[17909]: info: Loading plugin "appearance"... Sep 07 04:02:40 volumio volumio[17909]: info: Loading plugin "network"... Sep 07 04:02:40 volumio volumio[17909]: info: Refreshing Cached IP Addresses Sep 07 04:02:40 volumio sudo[17962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 04:02:40 volumio sudo[17962]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:41 volumio sudo[17964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 04:02:41 volumio sudo[17962]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:41 volumio sudo[17964]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:41 volumio sudo[17964]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "services"... Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "alsa_controller"... Sep 07 04:02:41 volumio sudo[17970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 04:02:41 volumio sudo[17970]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:41 volumio sudo[17970]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:41 volumio volumio[17909]: warn: Unable to locate the audio output device PS Audio USB Audio 2.0. Please configure a valid output device. Sep 07 04:02:41 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "wizard"... Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "networkfs"... Sep 07 04:02:41 volumio volumio[17909]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:41 volumio volumio[17909]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:41 volumio volumio[17909]: info: Starting Udev Watcher for removable devices Sep 07 04:02:41 volumio volumio[17909]: info: Ignoring mount for partition: boot Sep 07 04:02:41 volumio volumio[17909]: info: Ignoring mount for partition: volumio Sep 07 04:02:41 volumio volumio[17909]: info: Ignoring mount for partition: volumio_data Sep 07 04:02:41 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "volumio_command_line_client"... Sep 07 04:02:41 volumio volumio[17909]: info: Plugin upnp is not enabled Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "my_music"... Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "mpd"... Sep 07 04:02:41 volumio volumio[17909]: info: Plugin upnp_browser is not enabled Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "alarm-clock"... Sep 07 04:02:41 volumio volumio[17909]: info: Plugin airplay_emulation is not enabled Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "last_100"... Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "webradio"... Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "i2s_dacs"... Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "volumiodiscovery"... Sep 07 04:02:41 volumio volumio[17909]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:41 volumio volumio[17909]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:41 volumio volumio[17909]: *** WARNING *** For more information see Sep 07 04:02:41 volumio node[17909]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:41 volumio volumio[17909]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:41 volumio volumio[17909]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:41 volumio volumio[17909]: *** WARNING *** For more information see Sep 07 04:02:41 volumio node[17909]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:41 volumio node[17909]: *** WARNING *** For more information see Sep 07 04:02:41 volumio node[17909]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:41 volumio node[17909]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:41 volumio node[17909]: *** WARNING *** For more information see Sep 07 04:02:41 volumio volumio[17909]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 04:02:41 volumio volumio[17909]: info: Discovery: Started advertising with name: Volumio Sep 07 04:02:41 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:41 volumio volumio[17909]: info: Loading plugin "soundcloud"... Sep 07 04:02:42 volumio volumio[17909]: info: Loading plugin "spop"... Sep 07 04:02:42 volumio volumio-remote-updater[554]: [2025-09-07 04:02:42] [connect] Successful connection Sep 07 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Sep 07 04:02:42 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:42 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:42 volumio go-librespot[17989]: Librespot-go daemon starting... Sep 07 04:02:42 volumio go-librespot[17989]: time="2025-09-07T04:02:42+02:00" level=info msg="generated new device id: a6ae28505c3106e011651da6d6df4582cdc483d6" Sep 07 04:02:42 volumio go-librespot[17989]: time="2025-09-07T04:02:42+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:43 volumio volumio[17909]: info: Loading plugin "squeezelite_mc"... Sep 07 04:02:43 volumio volumio[17909]: info: Loading plugin "outputs"... Sep 07 04:02:43 volumio volumio[17909]: info: Loading plugin "albumart"... Sep 07 04:02:43 volumio volumio[17909]: info: Plugin example_plugin is not enabled Sep 07 04:02:43 volumio volumio[17909]: info: Loading plugin "inputs"... Sep 07 04:02:44 volumio volumio[17909]: info: Loading plugin "updater_comm"... Sep 07 04:02:44 volumio volumio[17909]: info: Plugin mpdemulation is not enabled Sep 07 04:02:44 volumio volumio[17909]: info: Loading plugin "rest_api"... Sep 07 04:02:44 volumio volumio[17909]: info: Loading plugin "websocket"... Sep 07 04:02:44 volumio volumio[17909]: info: Starting Socket.io Server version 2.3.0 Sep 07 04:02:44 volumio volumio[17909]: info: Loading plugin "radio_paradise"... Sep 07 04:02:44 volumio volumio[17909]: Forking 3 albumart workers Sep 07 04:02:44 volumio volumio[17909]: info: Applying required configuration parameters for plugin radio_paradise Sep 07 04:02:44 volumio volumio[17909]: info: [1757210564555] [RadioParadise] API delay: 3 Sep 07 04:02:44 volumio volumio[17909]: info: Loading plugin "backup_restore"... Sep 07 04:02:44 volumio wpa_supplicant[865]: wlan0: Failed to initiate sched scan Sep 07 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:44 volumio volumio[17909]: info: Applying required configuration parameters for plugin backup_restore Sep 07 04:02:44 volumio volumio[17909]: info: Loading plugin "music_services_shield"... Sep 07 04:02:45 volumio volumio[17909]: info: Applying required configuration parameters for plugin music_services_shield Sep 07 04:02:45 volumio volumio[17909]: info: Loading plugin "Systeminfo"... Sep 07 04:02:45 volumio volumio[17909]: Starting albumart workers Sep 07 04:02:45 volumio volumio[17909]: Starting albumart workers Sep 07 04:02:45 volumio volumio[17909]: info: Loading i18n strings for locale en Sep 07 04:02:45 volumio volumio[17909]: Updating browse sources language Sep 07 04:02:45 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:45 volumio volumio[17909]: Starting albumart workers Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::initPlayerControls Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:46 volumio volumio[17909]: Express server listening on port 3000 Sep 07 04:02:46 volumio volumio[17909]: [Metrics] WebUI: 7s 346.49ms Sep 07 04:02:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Sep 07 04:02:46 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:46 volumio volumio[17909]: info: CoreStateMachine::resetVolumioState Sep 07 04:02:46 volumio volumio[17909]: info: CoreStateMachine::getcurrentVolume Sep 07 04:02:46 volumio volumio[17909]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:46 volumio volumio[17909]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:46 volumio volumio[17909]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:46 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:46 volumio go-librespot[18035]: Librespot-go daemon starting... Sep 07 04:02:46 volumio go-librespot[18035]: time="2025-09-07T04:02:46+02:00" level=info msg="generated new device id: 50ac410c5d8eccde689ec4c450d139f30c1f1211" Sep 07 04:02:46 volumio go-librespot[18035]: time="2025-09-07T04:02:46+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:46 volumio volumio[17909]: info: Volumio Network Manager: Network status updated: 0 Sep 07 04:02:46 volumio volumio[17909]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:46 volumio volumio[17909]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 07 04:02:46 volumio volumio[17909]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 07 04:02:46 volumio volumio[17909]: info: Cannot use regular scanning, forcing with ap-force Sep 07 04:02:46 volumio sudo[18045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 07 04:02:46 volumio sudo[18045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:47 volumio autossh[26375]: starting ssh (count 11) Sep 07 04:02:47 volumio autossh[26375]: ssh child pid is 18063 Sep 07 04:02:47 volumio volumiossh-tunnel[26373]: ssh: Could not resolve hostname eu4.myvolumio.org: Device or resource busy Sep 07 04:02:47 volumio autossh[26375]: ssh exited with error status 255; restarting ssh Sep 07 04:02:48 volumio sudo[18045]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:49 volumio volumio[17909]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::pushState Sep 07 04:02:49 volumio volumio[17909]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::updateTrackBlock Sep 07 04:02:49 volumio volumio[17909]: info: CorePlayQueue::getTrackBlock Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:49 volumio volumio[17909]: info: Reloading queue from file Sep 07 04:02:49 volumio volumio[17909]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::pushState Sep 07 04:02:49 volumio volumio[17909]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::setRandom false Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::pushState Sep 07 04:02:49 volumio volumio[17909]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:49 volumio volumio[17909]: info: Setting Device type: Raspberry PI Sep 07 04:02:49 volumio volumio[17909]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::pushState Sep 07 04:02:49 volumio volumio[17909]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:49 volumio volumio[17909]: info: Completed loading Core Plugins Sep 07 04:02:49 volumio volumio[17909]: info: Preparing to generate the ALSA configuration file Sep 07 04:02:49 volumio volumio[17909]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:02:49 volumio volumio[17909]: info: Output device has changed, restarting MPD Sep 07 04:02:49 volumio sudo[18071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:49 volumio sudo[18071]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:49 volumio volumio[17909]: info: ___________ START PLUGINS ___________ Sep 07 04:02:49 volumio sudo[18071]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:49 volumio volumio[17909]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:02:49 volumio volumio[17909]: info: Creating MPD Configuration file Sep 07 04:02:49 volumio sudo[18073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:49 volumio sudo[18073]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:49 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:02:49 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:49 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:49 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:02:49 volumio sudo[18077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:02:49 volumio sudo[18077]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:49 volumio volumio[17909]: info: [1757210569269] CoreMusicLibrary::Adding element Last_100 Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:49 volumio volumio[17909]: info: [1757210569275] CoreMusicLibrary::Adding element Webradio Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:49 volumio sudo[18077]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:49 volumio volumio[17909]: info: Initializing BBC Radios Sep 07 04:02:49 volumio sudo[18080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:02:49 volumio sudo[18080]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Sep 07 04:02:49 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:49 volumio volumio[17909]: info: [1757210569369] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:49 volumio volumio[17909]: Cannot find translation for source SoundCloud Sep 07 04:02:49 volumio volumio[17909]: info: Creating Spotify config file Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:49 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:49 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:02:49 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:02:49 volumio go-librespot[18085]: Librespot-go daemon starting... Sep 07 04:02:49 volumio volumio[17909]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:02:49 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:02:49 volumio volumio[17909]: info: [1757210569437] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:49 volumio volumio[17909]: Cannot find translation for source SoundCloud Sep 07 04:02:49 volumio volumio[17909]: Cannot find translation for source Radio Paradise Sep 07 04:02:49 volumio go-librespot[18085]: time="2025-09-07T04:02:49+02:00" level=info msg="generated new device id: c8ee7b27b84844eaf834db6a5750a2e4d1fa7ce7" Sep 07 04:02:49 volumio go-librespot[18085]: time="2025-09-07T04:02:49+02:00" level=fatal msg="unknown credentials: " Sep 07 04:02:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:49 volumio sudo[18094]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:02:49 volumio sudo[18094]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:49 volumio sudo[18094]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:49 volumio volumio[17909]: info: Volumio Calling Home Sep 07 04:02:49 volumio sudo[18127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:02:49 volumio sudo[18127]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:49 volumio volumio[17909]: info: [squeezelite_mc] Proxy server started on port 37583 Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:49 volumio volumio[17909]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:02:49 volumio volumio[17909]: info: MPD Permissions set Sep 07 04:02:49 volumio volumio[17909]: info: MPD Permissions set Sep 07 04:02:49 volumio volumio[17909]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:02:49 volumio volumio[17909]: info: CoreStateMachine::pushState Sep 07 04:02:49 volumio volumio[17909]: info: CorePlayQueue::getTrack 0 Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::volumioPushState Sep 07 04:02:49 volumio volumio[17909]: info: [squeezelite_mc] Server discovery started Sep 07 04:02:49 volumio volumio[17909]: info: [squeezelite_mc] Player finder started Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:49 volumio volumio[17909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:49 volumio volumio[17909]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:02:50 volumio volumio[17909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:50 volumio volumio[17909]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:02:50 volumio volumio[17909]: at doSend (dgram.js:692:16) Sep 07 04:02:50 volumio volumio[17909]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:02:50 volumio volumio[17909]: at afterDns (dgram.js:638:5) Sep 07 04:02:50 volumio volumio[17909]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:02:50 volumio volumio[17909]: errno: -101, Sep 07 04:02:50 volumio volumio[17909]: code: 'ENETUNREACH', Sep 07 04:02:50 volumio volumio[17909]: syscall: 'send', Sep 07 04:02:50 volumio volumio[17909]: address: '255.255.255.255', Sep 07 04:02:50 volumio volumio[17909]: port: 3483 Sep 07 04:02:50 volumio volumio[17909]: } Sep 07 04:02:50 volumio volumio[17909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:02:50 volumio sudo[18127]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:50 volumio sudo[18159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:01 Sep 07 04:02:50 volumio sudo[18159]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:50 volumio sudo[18159]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:50 volumio sudo[18073]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:50 volumio sudo[18080]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 07 04:02:51 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:51 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:51 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 07 04:02:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Sep 07 04:02:51 volumio systemd[1]: Started dynamicswap service. Sep 07 04:02:51 volumio systemd[1]: Stopped Volumio Backend Module. Sep 07 04:02:51 volumio systemd[1]: Started Volumio Backend Module. Sep 07 04:02:51 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:02:51 volumio mpd[18117]: Sep 07 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 04:02:51 volumio systemd[1]: Started Music Player Daemon. Sep 07 04:02:51 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 07 04:02:52 volumio volumio[18187]: cset: --> shielding system active with Sep 07 04:02:52 volumio volumio[18187]: cset: "system" cpuset of CPUSPEC(1-3) with 53 tasks running Sep 07 04:02:52 volumio volumio[18187]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 07 04:02:52 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 07 04:02:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Sep 07 04:02:52 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:52 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:52 volumio go-librespot[18202]: Librespot-go daemon starting... Sep 07 04:02:52 volumio go-librespot[18202]: time="2025-09-07T04:02:52+02:00" level=info msg="generated new device id: b07e6527575f46534ee2b9575a3dc5ba3a58ffb1" Sep 07 04:02:52 volumio go-librespot[18202]: time="2025-09-07T04:02:52+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:52 volumio volumio[18173]: info: ------------------------------------------- Sep 07 04:02:52 volumio volumio[18173]: info: ----- Volumio3 ---- Sep 07 04:02:52 volumio volumio[18173]: info: ------------------------------------------- Sep 07 04:02:52 volumio volumio[18173]: info: ----- System startup ---- Sep 07 04:02:52 volumio volumio[18173]: info: ------------------------------------------- Sep 07 04:02:53 volumio volumio[18173]: info: MYVOLUMIO Environment detected Sep 07 04:02:53 volumio volumio[18173]: info: Plugin folders cleanup Sep 07 04:02:53 volumio volumio[18173]: info: Scanning into folder /volumio/app/plugins/ Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category audio_interface Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category miscellanea Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category music_service Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category plugins.json Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category system_controller Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category user_interface Sep 07 04:02:53 volumio volumio[18173]: info: Scanning into folder /data/plugins/ Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category music_service Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category system_controller Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category system_hardware Sep 07 04:02:53 volumio volumio[18173]: info: Scanning category user_interface Sep 07 04:02:53 volumio volumio[18173]: info: Plugin folders cleanup completed Sep 07 04:02:53 volumio volumio[18173]: info: ------------------------------------------- Sep 07 04:02:53 volumio volumio[18173]: info: ----- Core plugins startup ---- Sep 07 04:02:53 volumio volumio[18173]: info: ------------------------------------------- Sep 07 04:02:53 volumio volumio[18173]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 04:02:53 volumio volumio[18173]: info: Adding plugin upnp to MyMusic Plugins Sep 07 04:02:53 volumio volumio[18173]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 04:02:53 volumio volumio[18173]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 04:02:53 volumio volumio[18173]: info: Loading plugins from folder /data/plugins/ Sep 07 04:02:53 volumio volumio[18173]: info: Loading plugin "system"... Sep 07 04:02:53 volumio volumio[18173]: info: Loading plugin "appearance"... Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "network"... Sep 07 04:02:54 volumio volumio[18173]: info: Refreshing Cached IP Addresses Sep 07 04:02:54 volumio sudo[18223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 04:02:54 volumio sudo[18223]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:54 volumio sudo[18223]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:54 volumio sudo[18225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 04:02:54 volumio sudo[18225]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:54 volumio sudo[18225]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "services"... Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "alsa_controller"... Sep 07 04:02:54 volumio sudo[18234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 04:02:54 volumio sudo[18234]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:02:54 volumio sudo[18234]: pam_unix(sudo:session): session closed for user root Sep 07 04:02:54 volumio volumio[18173]: warn: Unable to locate the audio output device PS Audio USB Audio 2.0. Please configure a valid output device. Sep 07 04:02:54 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "wizard"... Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "networkfs"... Sep 07 04:02:54 volumio volumio[18173]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:54 volumio volumio[18173]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:02:54 volumio volumio[18173]: info: Starting Udev Watcher for removable devices Sep 07 04:02:54 volumio volumio[18173]: info: Ignoring mount for partition: boot Sep 07 04:02:54 volumio volumio[18173]: info: Ignoring mount for partition: volumio Sep 07 04:02:54 volumio volumio[18173]: info: Ignoring mount for partition: volumio_data Sep 07 04:02:54 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "volumio_command_line_client"... Sep 07 04:02:54 volumio volumio[18173]: info: Plugin upnp is not enabled Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "my_music"... Sep 07 04:02:54 volumio volumio[18173]: info: Loading plugin "mpd"... Sep 07 04:02:54 volumio volumio-remote-updater[554]: [2025-09-07 04:02:54] [connect] Successful connection Sep 07 04:02:55 volumio volumio[18173]: info: Plugin upnp_browser is not enabled Sep 07 04:02:55 volumio volumio[18173]: info: Loading plugin "alarm-clock"... Sep 07 04:02:55 volumio volumio[18173]: info: Plugin airplay_emulation is not enabled Sep 07 04:02:55 volumio volumio[18173]: info: Loading plugin "last_100"... Sep 07 04:02:55 volumio volumio[18173]: info: Loading plugin "webradio"... Sep 07 04:02:55 volumio volumio[18173]: info: Loading plugin "i2s_dacs"... Sep 07 04:02:55 volumio volumio[18173]: info: Loading plugin "volumiodiscovery"... Sep 07 04:02:55 volumio volumio[18173]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:55 volumio volumio[18173]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:55 volumio volumio[18173]: *** WARNING *** For more information see Sep 07 04:02:55 volumio node[18173]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:55 volumio volumio[18173]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:55 volumio volumio[18173]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:55 volumio volumio[18173]: *** WARNING *** For more information see Sep 07 04:02:55 volumio node[18173]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:55 volumio node[18173]: *** WARNING *** For more information see Sep 07 04:02:55 volumio node[18173]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:02:55 volumio node[18173]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:02:55 volumio node[18173]: *** WARNING *** For more information see Sep 07 04:02:55 volumio volumio[18173]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 04:02:55 volumio volumio[18173]: info: Discovery: Started advertising with name: Volumio Sep 07 04:02:55 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:02:55 volumio volumio[18173]: info: Loading plugin "soundcloud"... Sep 07 04:02:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Sep 07 04:02:55 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:55 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:55 volumio go-librespot[18250]: Librespot-go daemon starting... Sep 07 04:02:55 volumio go-librespot[18250]: time="2025-09-07T04:02:55+02:00" level=info msg="generated new device id: 184d301b530f41e8b8c4756a5306212ad5018840" Sep 07 04:02:55 volumio go-librespot[18250]: time="2025-09-07T04:02:55+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:55 volumio volumio[18173]: info: Loading plugin "spop"... Sep 07 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:02:56 volumio volumio[18173]: info: Loading plugin "squeezelite_mc"... Sep 07 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:02:57 volumio volumio[18173]: info: Loading plugin "outputs"... Sep 07 04:02:57 volumio volumio[18173]: info: Loading plugin "albumart"... Sep 07 04:02:57 volumio volumio[18173]: info: Plugin example_plugin is not enabled Sep 07 04:02:57 volumio volumio[18173]: info: Loading plugin "inputs"... Sep 07 04:02:57 volumio volumio[18173]: info: Loading plugin "updater_comm"... Sep 07 04:02:57 volumio volumio[18173]: info: Plugin mpdemulation is not enabled Sep 07 04:02:57 volumio volumio[18173]: info: Loading plugin "rest_api"... Sep 07 04:02:57 volumio volumio[18173]: info: Loading plugin "websocket"... Sep 07 04:02:57 volumio volumio[18173]: info: Starting Socket.io Server version 2.3.0 Sep 07 04:02:57 volumio volumio[18173]: info: Loading plugin "radio_paradise"... Sep 07 04:02:57 volumio volumio[18173]: Forking 3 albumart workers Sep 07 04:02:58 volumio volumio[18173]: info: Applying required configuration parameters for plugin radio_paradise Sep 07 04:02:58 volumio volumio[18173]: info: [1757210578237] [RadioParadise] API delay: 3 Sep 07 04:02:58 volumio volumio[18173]: info: Loading plugin "backup_restore"... Sep 07 04:02:58 volumio volumio[18173]: info: Applying required configuration parameters for plugin backup_restore Sep 07 04:02:58 volumio volumio[18173]: info: Loading plugin "music_services_shield"... Sep 07 04:02:58 volumio wpa_supplicant[865]: wlan0: Failed to initiate sched scan Sep 07 04:02:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:02:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:02:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:02:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:02:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:02:58 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:02:58 volumio volumio[18173]: info: Applying required configuration parameters for plugin music_services_shield Sep 07 04:02:58 volumio volumio[18173]: info: Loading plugin "Systeminfo"... Sep 07 04:02:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:02:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Sep 07 04:02:58 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:02:58 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:02:58 volumio go-librespot[18288]: Librespot-go daemon starting... Sep 07 04:02:59 volumio go-librespot[18288]: time="2025-09-07T04:02:59+02:00" level=info msg="generated new device id: eae5ee626c6429e58fe3f74b385d22c469fff16c" Sep 07 04:02:59 volumio go-librespot[18288]: time="2025-09-07T04:02:59+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:02:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:02:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:02:59 volumio volumio[18173]: Starting albumart workers Sep 07 04:02:59 volumio volumio[18173]: Starting albumart workers Sep 07 04:02:59 volumio volumio[18173]: Starting albumart workers Sep 07 04:02:59 volumio volumio[18173]: info: Loading i18n strings for locale en Sep 07 04:02:59 volumio volumio[18173]: Updating browse sources language Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::initPlayerControls Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:02:59 volumio volumio[18173]: Express server listening on port 3000 Sep 07 04:02:59 volumio volumio[18173]: [Metrics] WebUI: 7s 581.38ms Sep 07 04:02:59 volumio volumio[18173]: info: CoreStateMachine::resetVolumioState Sep 07 04:02:59 volumio volumio[18173]: info: CoreStateMachine::getcurrentVolume Sep 07 04:02:59 volumio volumio[18173]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:02:59 volumio volumio[18173]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:02:59 volumio volumio[18173]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:03:00 volumio volumio[18173]: info: Volumio Network Manager: Network status updated: 0 Sep 07 04:03:00 volumio volumio[18173]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:00 volumio volumio[18173]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 07 04:03:00 volumio volumio[18173]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 07 04:03:00 volumio volumio[18173]: info: Cannot use regular scanning, forcing with ap-force Sep 07 04:03:00 volumio sudo[18320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 07 04:03:00 volumio sudo[18320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Sep 07 04:03:02 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:02 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:02 volumio go-librespot[18326]: Librespot-go daemon starting... Sep 07 04:03:02 volumio go-librespot[18326]: time="2025-09-07T04:03:02+02:00" level=info msg="generated new device id: 22e800a218f6c8358aa5aae4796e7741245ac12b" Sep 07 04:03:02 volumio go-librespot[18326]: time="2025-09-07T04:03:02+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:02 volumio sudo[18320]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:02 volumio volumio[18173]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:02 volumio volumio[18173]: info: CoreStateMachine::pushState Sep 07 04:03:02 volumio volumio[18173]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:02 volumio volumio[18173]: info: CoreStateMachine::updateTrackBlock Sep 07 04:03:02 volumio volumio[18173]: info: CorePlayQueue::getTrackBlock Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:03:02 volumio volumio[18173]: info: Reloading queue from file Sep 07 04:03:02 volumio volumio[18173]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:02 volumio volumio[18173]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:03:02 volumio volumio[18173]: info: CoreStateMachine::pushState Sep 07 04:03:02 volumio volumio[18173]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:02 volumio volumio[18173]: info: CoreStateMachine::setRandom false Sep 07 04:03:02 volumio volumio[18173]: info: CoreStateMachine::pushState Sep 07 04:03:02 volumio volumio[18173]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:02 volumio volumio[18173]: info: Setting Device type: Raspberry PI Sep 07 04:03:02 volumio volumio[18173]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:02 volumio volumio[18173]: info: CoreStateMachine::pushState Sep 07 04:03:02 volumio volumio[18173]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:02 volumio volumio[18173]: info: Completed loading Core Plugins Sep 07 04:03:02 volumio volumio[18173]: info: Preparing to generate the ALSA configuration file Sep 07 04:03:02 volumio volumio[18173]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:03:02 volumio volumio[18173]: info: Output device has changed, restarting MPD Sep 07 04:03:02 volumio sudo[18340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:03:02 volumio sudo[18340]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:02 volumio volumio[18173]: info: ___________ START PLUGINS ___________ Sep 07 04:03:02 volumio sudo[18340]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:02 volumio volumio[18173]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:03:02 volumio volumio[18173]: info: Creating MPD Configuration file Sep 07 04:03:02 volumio sudo[18342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:03:02 volumio sudo[18342]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:02 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:03:02 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:03:02 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:03:02 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:03:02 volumio sudo[18346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:03:02 volumio sudo[18346]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:02 volumio volumio[18173]: info: [1757210582900] CoreMusicLibrary::Adding element Last_100 Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:02 volumio sudo[18346]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:02 volumio volumio[18173]: info: [1757210582906] CoreMusicLibrary::Adding element Webradio Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:02 volumio volumio[18173]: info: Initializing BBC Radios Sep 07 04:03:02 volumio sudo[18348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:03:02 volumio sudo[18348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:02 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:03:02 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:02 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:03 volumio volumio[18173]: info: [1757210583004] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:03 volumio volumio[18173]: Cannot find translation for source SoundCloud Sep 07 04:03:03 volumio volumio[18173]: info: Creating Spotify config file Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:03 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:03:03 volumio volumio[18173]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:03 volumio volumio[18173]: info: [1757210583047] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:03 volumio volumio[18173]: Cannot find translation for source SoundCloud Sep 07 04:03:03 volumio volumio[18173]: Cannot find translation for source Radio Paradise Sep 07 04:03:03 volumio sudo[18358]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:03:03 volumio sudo[18358]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:03 volumio sudo[18358]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:03 volumio volumio[18173]: info: Volumio Calling Home Sep 07 04:03:03 volumio sudo[18390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:03:03 volumio sudo[18390]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:03 volumio volumio[18173]: info: [squeezelite_mc] Proxy server started on port 41751 Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:03 volumio volumio[18173]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:03 volumio volumio[18173]: info: MPD Permissions set Sep 07 04:03:03 volumio volumio[18173]: info: MPD Permissions set Sep 07 04:03:03 volumio volumio[18173]: info: Spotify config file written Sep 07 04:03:03 volumio sudo[18409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 07 04:03:03 volumio sudo[18409]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:03 volumio volumio[18173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 07 04:03:03 volumio volumio[18173]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:03 volumio volumio[18173]: info: CoreStateMachine::pushState Sep 07 04:03:03 volumio volumio[18173]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:03 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:03 volumio volumio[18173]: info: [squeezelite_mc] Server discovery started Sep 07 04:03:03 volumio volumio[18173]: info: [squeezelite_mc] Player finder started Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:03 volumio volumio[18173]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:03:03 volumio volumio[18173]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:03:03 volumio volumio[18173]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:03:03 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:03 volumio volumio[18173]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:03:03 volumio volumio[18173]: at doSend (dgram.js:692:16) Sep 07 04:03:03 volumio volumio[18173]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:03:03 volumio volumio[18173]: at afterDns (dgram.js:638:5) Sep 07 04:03:03 volumio volumio[18173]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:03:03 volumio volumio[18173]: errno: -101, Sep 07 04:03:03 volumio volumio[18173]: code: 'ENETUNREACH', Sep 07 04:03:03 volumio volumio[18173]: syscall: 'send', Sep 07 04:03:03 volumio volumio[18173]: address: '255.255.255.255', Sep 07 04:03:03 volumio volumio[18173]: port: 3483 Sep 07 04:03:03 volumio volumio[18173]: } Sep 07 04:03:03 volumio volumio[18173]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:03:03 volumio go-librespot[18411]: Librespot-go daemon starting... Sep 07 04:03:03 volumio go-librespot[18411]: time="2025-09-07T04:03:03+02:00" level=info msg="generated new device id: e8e24ef3af3e48b1039e12962a2b92c05982bcc5" Sep 07 04:03:03 volumio sudo[18409]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:03 volumio go-librespot[18411]: time="2025-09-07T04:03:03+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:04 volumio sudo[18390]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:03:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:03:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:03:04 volumio sudo[18432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:02 Sep 07 04:03:04 volumio sudo[18432]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:03:04 volumio sudo[18432]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:04 volumio sudo[18348]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:04 volumio sudo[18342]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 07 04:03:04 volumio systemd[1]: Started dynamicswap service. Sep 07 04:03:04 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:03:04 volumio mpd[18378]: Sep 07 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 04:03:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 07 04:03:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Sep 07 04:03:04 volumio systemd[1]: Started dynamicswap service. Sep 07 04:03:04 volumio systemd[1]: Stopped Volumio Backend Module. Sep 07 04:03:04 volumio systemd[1]: Started Volumio Backend Module. Sep 07 04:03:04 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:03:05 volumio systemd[1]: Started Music Player Daemon. Sep 07 04:03:05 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 07 04:03:05 volumio volumio[18460]: cset: --> shielding system active with Sep 07 04:03:05 volumio volumio[18460]: cset: "system" cpuset of CPUSPEC(1-3) with 53 tasks running Sep 07 04:03:05 volumio volumio[18460]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 07 04:03:05 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 07 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:03:06 volumio volumio[18446]: info: ------------------------------------------- Sep 07 04:03:06 volumio volumio[18446]: info: ----- Volumio3 ---- Sep 07 04:03:06 volumio volumio[18446]: info: ------------------------------------------- Sep 07 04:03:06 volumio volumio[18446]: info: ----- System startup ---- Sep 07 04:03:06 volumio volumio[18446]: info: ------------------------------------------- Sep 07 04:03:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Sep 07 04:03:06 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:06 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:06 volumio go-librespot[18483]: Librespot-go daemon starting... Sep 07 04:03:06 volumio go-librespot[18483]: time="2025-09-07T04:03:06+02:00" level=info msg="generated new device id: 81a2c91594eb2af83c34800e95248997e04318f5" Sep 07 04:03:06 volumio go-librespot[18483]: time="2025-09-07T04:03:06+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:07 volumio volumio[18446]: info: MYVOLUMIO Environment detected Sep 07 04:03:07 volumio volumio[18446]: info: Plugin folders cleanup Sep 07 04:03:07 volumio volumio[18446]: info: Scanning into folder /volumio/app/plugins/ Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category audio_interface Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category miscellanea Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category music_service Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category plugins.json Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category system_controller Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category user_interface Sep 07 04:03:07 volumio volumio[18446]: info: Scanning into folder /data/plugins/ Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category music_service Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category system_controller Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category system_hardware Sep 07 04:03:07 volumio volumio[18446]: info: Scanning category user_interface Sep 07 04:03:07 volumio volumio[18446]: info: Plugin folders cleanup completed Sep 07 04:03:07 volumio volumio[18446]: info: ------------------------------------------- Sep 07 04:03:07 volumio volumio[18446]: info: ----- Core plugins startup ---- Sep 07 04:03:07 volumio volumio[18446]: info: ------------------------------------------- Sep 07 04:03:07 volumio volumio[18446]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 04:03:07 volumio volumio[18446]: info: Adding plugin upnp to MyMusic Plugins Sep 07 04:03:07 volumio volumio[18446]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 04:03:07 volumio volumio[18446]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 04:03:07 volumio volumio[18446]: info: Loading plugins from folder /data/plugins/ Sep 07 04:03:07 volumio volumio[18446]: info: Loading plugin "system"... Sep 07 04:03:07 volumio volumio[18446]: info: Loading plugin "appearance"... Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "network"... Sep 07 04:03:08 volumio volumio[18446]: info: Refreshing Cached IP Addresses Sep 07 04:03:08 volumio sudo[18496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 04:03:08 volumio sudo[18496]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:08 volumio sudo[18498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 04:03:08 volumio sudo[18498]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:08 volumio sudo[18496]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:08 volumio sudo[18498]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "services"... Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "alsa_controller"... Sep 07 04:03:08 volumio sudo[18506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 04:03:08 volumio sudo[18506]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:08 volumio sudo[18506]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:08 volumio volumio[18446]: warn: Unable to locate the audio output device PS Audio USB Audio 2.0. Please configure a valid output device. Sep 07 04:03:08 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "wizard"... Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "networkfs"... Sep 07 04:03:08 volumio volumio[18446]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:03:08 volumio volumio[18446]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:03:08 volumio volumio[18446]: info: Starting Udev Watcher for removable devices Sep 07 04:03:08 volumio volumio[18446]: info: Ignoring mount for partition: boot Sep 07 04:03:08 volumio volumio[18446]: info: Ignoring mount for partition: volumio Sep 07 04:03:08 volumio volumio[18446]: info: Ignoring mount for partition: volumio_data Sep 07 04:03:08 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "volumio_command_line_client"... Sep 07 04:03:08 volumio volumio[18446]: info: Plugin upnp is not enabled Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "my_music"... Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "mpd"... Sep 07 04:03:08 volumio volumio[18446]: info: Plugin upnp_browser is not enabled Sep 07 04:03:08 volumio volumio[18446]: info: Loading plugin "alarm-clock"... Sep 07 04:03:09 volumio volumio[18446]: info: Plugin airplay_emulation is not enabled Sep 07 04:03:09 volumio volumio[18446]: info: Loading plugin "last_100"... Sep 07 04:03:09 volumio volumio[18446]: info: Loading plugin "webradio"... Sep 07 04:03:09 volumio volumio[18446]: info: Loading plugin "i2s_dacs"... Sep 07 04:03:09 volumio volumio[18446]: info: Loading plugin "volumiodiscovery"... Sep 07 04:03:09 volumio volumio[18446]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:09 volumio volumio[18446]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:09 volumio volumio[18446]: *** WARNING *** For more information see Sep 07 04:03:09 volumio node[18446]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:09 volumio volumio[18446]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:09 volumio volumio[18446]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:09 volumio volumio[18446]: *** WARNING *** For more information see Sep 07 04:03:09 volumio node[18446]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:09 volumio node[18446]: *** WARNING *** For more information see Sep 07 04:03:09 volumio node[18446]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:09 volumio node[18446]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:09 volumio node[18446]: *** WARNING *** For more information see Sep 07 04:03:09 volumio volumio[18446]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 04:03:09 volumio volumio[18446]: info: Discovery: Started advertising with name: Volumio Sep 07 04:03:09 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:03:09 volumio volumio[18446]: info: Loading plugin "soundcloud"... Sep 07 04:03:09 volumio volumio[18446]: info: Loading plugin "spop"... Sep 07 04:03:09 volumio volumio-remote-updater[554]: [2025-09-07 04:03:09] [connect] Successful connection Sep 07 04:03:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Sep 07 04:03:10 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:03:10 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:10 volumio go-librespot[18523]: Librespot-go daemon starting... Sep 07 04:03:10 volumio go-librespot[18523]: time="2025-09-07T04:03:10+02:00" level=info msg="generated new device id: 6638c87ee48325232b059ff8887db7ed2947dea2" Sep 07 04:03:10 volumio go-librespot[18523]: time="2025-09-07T04:03:10+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:03:10 volumio volumio[18446]: info: Loading plugin "squeezelite_mc"... Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "outputs"... Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "albumart"... Sep 07 04:03:11 volumio volumio[18446]: info: Plugin example_plugin is not enabled Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "inputs"... Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "updater_comm"... Sep 07 04:03:11 volumio volumio[18446]: info: Plugin mpdemulation is not enabled Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "rest_api"... Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "websocket"... Sep 07 04:03:11 volumio volumio[18446]: info: Starting Socket.io Server version 2.3.0 Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "radio_paradise"... Sep 07 04:03:11 volumio volumio[18446]: Forking 3 albumart workers Sep 07 04:03:11 volumio volumio[18446]: info: Applying required configuration parameters for plugin radio_paradise Sep 07 04:03:11 volumio volumio[18446]: info: [1757210591979] [RadioParadise] API delay: 3 Sep 07 04:03:11 volumio volumio[18446]: info: Loading plugin "backup_restore"... Sep 07 04:03:12 volumio wpa_supplicant[865]: wlan0: Failed to initiate sched scan Sep 07 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:03:12 volumio volumio[18446]: info: Applying required configuration parameters for plugin backup_restore Sep 07 04:03:12 volumio volumio[18446]: info: Loading plugin "music_services_shield"... Sep 07 04:03:12 volumio volumio[18446]: info: Applying required configuration parameters for plugin music_services_shield Sep 07 04:03:12 volumio volumio[18446]: info: Loading plugin "Systeminfo"... Sep 07 04:03:12 volumio volumio[18446]: Starting albumart workers Sep 07 04:03:12 volumio volumio[18446]: Starting albumart workers Sep 07 04:03:13 volumio volumio[18446]: Starting albumart workers Sep 07 04:03:13 volumio volumio[18446]: info: Loading i18n strings for locale en Sep 07 04:03:13 volumio volumio[18446]: Updating browse sources language Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Sep 07 04:03:13 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:13 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:13 volumio go-librespot[18558]: Librespot-go daemon starting... Sep 07 04:03:13 volumio go-librespot[18558]: time="2025-09-07T04:03:13+02:00" level=info msg="generated new device id: f1b127a71846348f01a569da7a7db05110327bef" Sep 07 04:03:13 volumio go-librespot[18558]: time="2025-09-07T04:03:13+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::initPlayerControls Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:03:13 volumio volumio[18446]: Express server listening on port 3000 Sep 07 04:03:13 volumio volumio[18446]: [Metrics] WebUI: 7s 466.77ms Sep 07 04:03:13 volumio volumio[18446]: info: CoreStateMachine::resetVolumioState Sep 07 04:03:13 volumio volumio[18446]: info: CoreStateMachine::getcurrentVolume Sep 07 04:03:13 volumio volumio[18446]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:13 volumio volumio[18446]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:03:13 volumio volumio[18446]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:03:13 volumio volumio[18446]: info: Volumio Network Manager: Network status updated: 0 Sep 07 04:03:13 volumio volumio[18446]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:13 volumio volumio[18446]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 07 04:03:13 volumio volumio[18446]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 07 04:03:13 volumio volumio[18446]: info: Cannot use regular scanning, forcing with ap-force Sep 07 04:03:13 volumio sudo[18587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 07 04:03:13 volumio sudo[18587]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:16 volumio sudo[18587]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:16 volumio volumio[18446]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:16 volumio volumio[18446]: info: CoreStateMachine::pushState Sep 07 04:03:16 volumio volumio[18446]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:16 volumio volumio[18446]: info: CoreStateMachine::updateTrackBlock Sep 07 04:03:16 volumio volumio[18446]: info: CorePlayQueue::getTrackBlock Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:03:16 volumio volumio[18446]: info: Reloading queue from file Sep 07 04:03:16 volumio volumio[18446]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:16 volumio volumio[18446]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:03:16 volumio volumio[18446]: info: CoreStateMachine::pushState Sep 07 04:03:16 volumio volumio[18446]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:16 volumio volumio[18446]: info: CoreStateMachine::setRandom false Sep 07 04:03:16 volumio volumio[18446]: info: CoreStateMachine::pushState Sep 07 04:03:16 volumio volumio[18446]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:16 volumio volumio[18446]: info: Setting Device type: Raspberry PI Sep 07 04:03:16 volumio volumio[18446]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:16 volumio volumio[18446]: info: CoreStateMachine::pushState Sep 07 04:03:16 volumio volumio[18446]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:16 volumio volumio[18446]: info: Completed loading Core Plugins Sep 07 04:03:16 volumio volumio[18446]: info: Preparing to generate the ALSA configuration file Sep 07 04:03:16 volumio volumio[18446]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:03:16 volumio volumio[18446]: info: Output device has changed, restarting MPD Sep 07 04:03:16 volumio sudo[18604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:03:16 volumio sudo[18604]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:16 volumio volumio[18446]: info: ___________ START PLUGINS ___________ Sep 07 04:03:16 volumio sudo[18604]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:16 volumio volumio[18446]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:03:16 volumio volumio[18446]: info: Creating MPD Configuration file Sep 07 04:03:16 volumio sudo[18606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:03:16 volumio sudo[18606]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Sep 07 04:03:16 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:03:16 volumio sudo[18610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:03:16 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:16 volumio sudo[18610]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:16 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:03:16 volumio volumio[18446]: info: [1757210596633] CoreMusicLibrary::Adding element Last_100 Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:16 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:03:16 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:16 volumio volumio[18446]: info: [1757210596638] CoreMusicLibrary::Adding element Webradio Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:16 volumio sudo[18610]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:16 volumio volumio[18446]: info: Initializing BBC Radios Sep 07 04:03:16 volumio sudo[18612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:03:16 volumio sudo[18612]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:16 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:16 volumio go-librespot[18614]: Librespot-go daemon starting... Sep 07 04:03:16 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:03:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:03:16 volumio go-librespot[18614]: time="2025-09-07T04:03:16+02:00" level=info msg="generated new device id: 92fc7123795d1d0568a534ad0575ef2f06fbf686" Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:16 volumio go-librespot[18614]: time="2025-09-07T04:03:16+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:03:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:16 volumio volumio[18446]: info: [1757210596766] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:16 volumio volumio[18446]: Cannot find translation for source SoundCloud Sep 07 04:03:16 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:03:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 07 04:03:16 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:03:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:03:16 volumio volumio[18446]: info: Creating Spotify config file Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:16 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:03:16 volumio volumio[18446]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:16 volumio volumio[18446]: info: [1757210596815] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:03:16 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:16 volumio volumio[18446]: Cannot find translation for source SoundCloud Sep 07 04:03:16 volumio volumio[18446]: Cannot find translation for source Radio Paradise Sep 07 04:03:16 volumio sudo[18632]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:03:16 volumio sudo[18632]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:16 volumio sudo[18632]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:17 volumio volumio[18446]: info: Volumio Calling Home Sep 07 04:03:17 volumio sudo[18661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:03:17 volumio sudo[18661]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:17 volumio volumio[18446]: info: [squeezelite_mc] Proxy server started on port 33691 Sep 07 04:03:17 volumio volumio[18446]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:17 volumio volumio[18446]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:17 volumio volumio[18446]: info: MPD Permissions set Sep 07 04:03:17 volumio volumio[18446]: info: MPD Permissions set Sep 07 04:03:17 volumio volumio[18446]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:17 volumio volumio[18446]: info: CoreStateMachine::pushState Sep 07 04:03:17 volumio volumio[18446]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:17 volumio volumio[18446]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:17 volumio volumio[18446]: info: [squeezelite_mc] Server discovery started Sep 07 04:03:17 volumio volumio[18446]: info: [squeezelite_mc] Player finder started Sep 07 04:03:17 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:17 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:17 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:17 volumio volumio[18446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:03:17 volumio volumio[18446]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:03:17 volumio volumio[18446]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:03:17 volumio volumio[18446]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:03:17 volumio volumio[18446]: at doSend (dgram.js:692:16) Sep 07 04:03:17 volumio volumio[18446]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:03:17 volumio volumio[18446]: at afterDns (dgram.js:638:5) Sep 07 04:03:17 volumio volumio[18446]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:03:17 volumio volumio[18446]: errno: -101, Sep 07 04:03:17 volumio volumio[18446]: code: 'ENETUNREACH', Sep 07 04:03:17 volumio volumio[18446]: syscall: 'send', Sep 07 04:03:17 volumio volumio[18446]: address: '255.255.255.255', Sep 07 04:03:17 volumio volumio[18446]: port: 3483 Sep 07 04:03:17 volumio volumio[18446]: } Sep 07 04:03:17 volumio volumio[18446]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:03:17 volumio sudo[18661]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:03:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:03:18 volumio sudo[18693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:02 Sep 07 04:03:18 volumio sudo[18693]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:03:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:03:18 volumio sudo[18693]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:18 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:18 volumio sudo[18606]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:18 volumio sudo[18612]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:18 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 07 04:03:18 volumio systemd[1]: Started dynamicswap service. Sep 07 04:03:18 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:03:18 volumio mpd[18647]: Sep 07 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 04:03:18 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 07 04:03:18 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Sep 07 04:03:18 volumio systemd[1]: Stopped Volumio Backend Module. Sep 07 04:03:18 volumio systemd[1]: Started Volumio Backend Module. Sep 07 04:03:18 volumio systemd[1]: Started dynamicswap service. Sep 07 04:03:18 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 07 04:03:19 volumio systemd[1]: Started Music Player Daemon. Sep 07 04:03:19 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 07 04:03:19 volumio volumio[18723]: cset: --> shielding system active with Sep 07 04:03:19 volumio volumio[18723]: cset: "system" cpuset of CPUSPEC(1-3) with 53 tasks running Sep 07 04:03:19 volumio volumio[18723]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 07 04:03:19 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 07 04:03:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Sep 07 04:03:19 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:19 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:19 volumio go-librespot[18739]: Librespot-go daemon starting... Sep 07 04:03:19 volumio go-librespot[18739]: time="2025-09-07T04:03:19+02:00" level=info msg="generated new device id: 3489c2ce15df27c53b24f294c34fba83a01dd030" Sep 07 04:03:19 volumio go-librespot[18739]: time="2025-09-07T04:03:19+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:03:20 volumio volumio[18708]: info: ------------------------------------------- Sep 07 04:03:20 volumio volumio[18708]: info: ----- Volumio3 ---- Sep 07 04:03:20 volumio volumio[18708]: info: ------------------------------------------- Sep 07 04:03:20 volumio volumio[18708]: info: ----- System startup ---- Sep 07 04:03:20 volumio volumio[18708]: info: ------------------------------------------- Sep 07 04:03:21 volumio volumio[18708]: info: MYVOLUMIO Environment detected Sep 07 04:03:21 volumio volumio[18708]: info: Plugin folders cleanup Sep 07 04:03:21 volumio volumio[18708]: info: Scanning into folder /volumio/app/plugins/ Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category audio_interface Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category miscellanea Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category music_service Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category plugins.json Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category system_controller Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category user_interface Sep 07 04:03:21 volumio volumio[18708]: info: Scanning into folder /data/plugins/ Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category music_service Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category system_controller Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category system_hardware Sep 07 04:03:21 volumio volumio[18708]: info: Scanning category user_interface Sep 07 04:03:21 volumio volumio[18708]: info: Plugin folders cleanup completed Sep 07 04:03:21 volumio volumio[18708]: info: ------------------------------------------- Sep 07 04:03:21 volumio volumio[18708]: info: ----- Core plugins startup ---- Sep 07 04:03:21 volumio volumio[18708]: info: ------------------------------------------- Sep 07 04:03:21 volumio volumio[18708]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 04:03:21 volumio volumio[18708]: info: Adding plugin upnp to MyMusic Plugins Sep 07 04:03:21 volumio volumio[18708]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 04:03:21 volumio volumio[18708]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 04:03:21 volumio volumio[18708]: info: Loading plugins from folder /data/plugins/ Sep 07 04:03:21 volumio volumio[18708]: info: Loading plugin "system"... Sep 07 04:03:21 volumio volumio[18708]: info: Loading plugin "appearance"... Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "network"... Sep 07 04:03:22 volumio volumio[18708]: info: Refreshing Cached IP Addresses Sep 07 04:03:22 volumio sudo[18760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 04:03:22 volumio sudo[18760]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:22 volumio sudo[18760]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:22 volumio sudo[18762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 04:03:22 volumio sudo[18762]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:22 volumio sudo[18762]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "services"... Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "alsa_controller"... Sep 07 04:03:22 volumio sudo[18772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 07 04:03:22 volumio sudo[18772]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:22 volumio sudo[18772]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:22 volumio volumio[18708]: warn: Unable to locate the audio output device PS Audio USB Audio 2.0. Please configure a valid output device. Sep 07 04:03:22 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "wizard"... Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "networkfs"... Sep 07 04:03:22 volumio volumio[18708]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:03:22 volumio volumio[18708]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Sep 07 04:03:22 volumio volumio[18708]: info: Starting Udev Watcher for removable devices Sep 07 04:03:22 volumio volumio[18708]: info: Ignoring mount for partition: boot Sep 07 04:03:22 volumio volumio[18708]: info: Ignoring mount for partition: volumio Sep 07 04:03:22 volumio volumio[18708]: info: Ignoring mount for partition: volumio_data Sep 07 04:03:22 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "volumio_command_line_client"... Sep 07 04:03:22 volumio volumio[18708]: info: Plugin upnp is not enabled Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "my_music"... Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "mpd"... Sep 07 04:03:22 volumio volumio[18708]: info: Plugin upnp_browser is not enabled Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "alarm-clock"... Sep 07 04:03:22 volumio volumio[18708]: info: Plugin airplay_emulation is not enabled Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "last_100"... Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "webradio"... Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "i2s_dacs"... Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "volumiodiscovery"... Sep 07 04:03:22 volumio volumio[18708]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:22 volumio volumio[18708]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:22 volumio volumio[18708]: *** WARNING *** For more information see Sep 07 04:03:22 volumio node[18708]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:22 volumio volumio[18708]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:22 volumio volumio[18708]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:22 volumio volumio[18708]: *** WARNING *** For more information see Sep 07 04:03:22 volumio node[18708]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:22 volumio node[18708]: *** WARNING *** For more information see Sep 07 04:03:22 volumio node[18708]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 04:03:22 volumio node[18708]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 04:03:22 volumio node[18708]: *** WARNING *** For more information see Sep 07 04:03:22 volumio volumio[18708]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 04:03:22 volumio volumio[18708]: info: Discovery: Started advertising with name: Volumio Sep 07 04:03:22 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 04:03:22 volumio volumio[18708]: info: Loading plugin "soundcloud"... Sep 07 04:03:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Sep 07 04:03:23 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:23 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:23 volumio go-librespot[18787]: Librespot-go daemon starting... Sep 07 04:03:23 volumio go-librespot[18787]: time="2025-09-07T04:03:23+02:00" level=info msg="generated new device id: 495ad526e880b4b0ced5e8e2ec3d5b393424c0de" Sep 07 04:03:23 volumio go-librespot[18787]: time="2025-09-07T04:03:23+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:23 volumio volumio[18708]: info: Loading plugin "spop"... Sep 07 04:03:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 07 04:03:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 07 04:03:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 07 04:03:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 07 04:03:24 volumio volumio[18708]: info: Loading plugin "squeezelite_mc"... Sep 07 04:03:24 volumio volumio-remote-updater[554]: [2025-09-07 04:03:24] [connect] Successful connection Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "outputs"... Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "albumart"... Sep 07 04:03:25 volumio volumio[18708]: info: Plugin example_plugin is not enabled Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "inputs"... Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "updater_comm"... Sep 07 04:03:25 volumio volumio[18708]: info: Plugin mpdemulation is not enabled Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "rest_api"... Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "websocket"... Sep 07 04:03:25 volumio volumio[18708]: info: Starting Socket.io Server version 2.3.0 Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "radio_paradise"... Sep 07 04:03:25 volumio volumio[18708]: Forking 3 albumart workers Sep 07 04:03:25 volumio volumio[18708]: info: Applying required configuration parameters for plugin radio_paradise Sep 07 04:03:25 volumio volumio[18708]: info: [1757210605679] [RadioParadise] API delay: 3 Sep 07 04:03:25 volumio volumio[18708]: info: Loading plugin "backup_restore"... Sep 07 04:03:26 volumio wpa_supplicant[865]: wlan0: Trying to associate with SSID 'DAVINCHI-5G' Sep 07 04:03:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Sep 07 04:03:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Sep 07 04:03:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Sep 07 04:03:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Sep 07 04:03:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Sep 07 04:03:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Sep 07 04:03:26 volumio volumio[18708]: info: Applying required configuration parameters for plugin backup_restore Sep 07 04:03:26 volumio volumio[18708]: info: Loading plugin "music_services_shield"... Sep 07 04:03:26 volumio volumio[18708]: info: Applying required configuration parameters for plugin music_services_shield Sep 07 04:03:26 volumio wpa_supplicant[865]: wlan0: Associated with f0:b4:d2:6b:1f:7b Sep 07 04:03:26 volumio wpa_supplicant[865]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:b4:d2:6b:1f:7b completed [id=0 id_str=] Sep 07 04:03:26 volumio wpa_supplicant[865]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 07 04:03:26 volumio dhcpcd[877]: wlan0: carrier acquired Sep 07 04:03:26 volumio volumio[18708]: info: Loading plugin "Systeminfo"... Sep 07 04:03:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:26 volumio dhcpcd[877]: wlan0: IAID eb:2a:05:b2 Sep 07 04:03:26 volumio dhcpcd[877]: wlan0: carrier lost Sep 07 04:03:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Sep 07 04:03:26 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:26 volumio dhcpcd[877]: wlan0: carrier acquired Sep 07 04:03:26 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:26 volumio go-librespot[18825]: Librespot-go daemon starting... Sep 07 04:03:26 volumio dhcpcd[877]: wlan0: IAID eb:2a:05:b2 Sep 07 04:03:26 volumio go-librespot[18825]: time="2025-09-07T04:03:26+02:00" level=info msg="generated new device id: 14726cff93aad796e8069f31198cf1b2cddad3b2" Sep 07 04:03:26 volumio go-librespot[18825]: time="2025-09-07T04:03:26+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:26 volumio volumio[18708]: Starting albumart workers Sep 07 04:03:26 volumio volumio[18708]: Starting albumart workers Sep 07 04:03:26 volumio dhcpcd[877]: wlan0: rebinding lease of 192.168.0.120 Sep 07 04:03:26 volumio dhcpcd[877]: wlan0: probing address 192.168.0.120/24 Sep 07 04:03:27 volumio dhcpcd[877]: wlan0: soliciting an IPv6 router Sep 07 04:03:27 volumio volumio[18708]: info: Loading i18n strings for locale en Sep 07 04:03:27 volumio volumio[18708]: Updating browse sources language Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:27 volumio volumio[18708]: Starting albumart workers Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::initPlayerControls Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:03:27 volumio volumio[18708]: Express server listening on port 3000 Sep 07 04:03:27 volumio volumio[18708]: [Metrics] WebUI: 7s 594.56ms Sep 07 04:03:27 volumio volumio[18708]: info: CoreStateMachine::resetVolumioState Sep 07 04:03:27 volumio volumio[18708]: info: CoreStateMachine::getcurrentVolume Sep 07 04:03:27 volumio volumio[18708]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:27 volumio volumio[18708]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:03:27 volumio volumio[18708]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Sep 07 04:03:27 volumio volumio[18708]: info: Volumio Network Manager: Network status updated: 0 Sep 07 04:03:27 volumio volumio[18708]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:27 volumio volumio[18708]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 07 04:03:27 volumio volumio[18708]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 07 04:03:27 volumio volumio[18708]: info: Cannot use regular scanning, forcing with ap-force Sep 07 04:03:27 volumio sudo[18901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 07 04:03:27 volumio sudo[18901]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 07 04:03:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Sep 07 04:03:29 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:29 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:29 volumio go-librespot[18911]: Librespot-go daemon starting... Sep 07 04:03:29 volumio go-librespot[18911]: time="2025-09-07T04:03:29+02:00" level=info msg="generated new device id: ff1c5fc64bff1f6a76d1cd7196ac03e8b1faa239" Sep 07 04:03:29 volumio go-librespot[18911]: time="2025-09-07T04:03:29+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:30 volumio sudo[18901]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:30 volumio volumio[18708]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:30 volumio volumio[18708]: info: CoreStateMachine::pushState Sep 07 04:03:30 volumio volumio[18708]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:30 volumio volumio[18708]: info: CoreStateMachine::updateTrackBlock Sep 07 04:03:30 volumio volumio[18708]: info: CorePlayQueue::getTrackBlock Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:03:30 volumio volumio[18708]: info: Reloading queue from file Sep 07 04:03:30 volumio volumio[18708]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:30 volumio volumio[18708]: info: CoreStateMachine::setRepeat false single undefined Sep 07 04:03:30 volumio volumio[18708]: info: CoreStateMachine::pushState Sep 07 04:03:30 volumio volumio[18708]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:30 volumio volumio[18708]: info: CoreStateMachine::setRandom false Sep 07 04:03:30 volumio volumio[18708]: info: CoreStateMachine::pushState Sep 07 04:03:30 volumio volumio[18708]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:30 volumio volumio[18708]: info: Setting Device type: Raspberry PI Sep 07 04:03:30 volumio volumio[18708]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:30 volumio volumio[18708]: info: CoreStateMachine::pushState Sep 07 04:03:30 volumio volumio[18708]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:30 volumio volumio[18708]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:30 volumio volumio[18708]: info: Completed loading Core Plugins Sep 07 04:03:30 volumio volumio[18708]: info: Preparing to generate the ALSA configuration file Sep 07 04:03:30 volumio volumio[18708]: info: Asound.conf file unchanged, so no further update is needed Sep 07 04:03:30 volumio volumio[18708]: info: Output device has changed, restarting MPD Sep 07 04:03:30 volumio sudo[18925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:03:30 volumio sudo[18925]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:31 volumio volumio[18708]: info: ___________ START PLUGINS ___________ Sep 07 04:03:31 volumio sudo[18925]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:31 volumio volumio[18708]: info: ControllerMpd::onStart: Initializing MPD Sep 07 04:03:31 volumio volumio[18708]: info: Creating MPD Configuration file Sep 07 04:03:31 volumio sudo[18927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:03:31 volumio sudo[18927]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:31 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 07 04:03:31 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 07 04:03:31 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 07 04:03:31 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 04:03:31 volumio sudo[18931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 04:03:31 volumio sudo[18931]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:31 volumio volumio[18708]: info: [1757210611050] CoreMusicLibrary::Adding element Last_100 Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:31 volumio sudo[18931]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:31 volumio volumio[18708]: info: [1757210611056] CoreMusicLibrary::Adding element Webradio Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:31 volumio volumio[18708]: info: Initializing BBC Radios Sep 07 04:03:31 volumio sudo[18933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 04:03:31 volumio sudo[18933]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:31 volumio systemd[1]: mpd.service: Succeeded. Sep 07 04:03:31 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 04:03:31 volumio systemd[1]: Starting Music Player Daemon... Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:31 volumio volumio[18708]: info: [1757210611166] CoreMusicLibrary::Adding element SoundCloud Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:31 volumio volumio[18708]: Cannot find translation for source SoundCloud Sep 07 04:03:31 volumio volumio[18708]: info: Creating Spotify config file Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:31 volumio volumio[18708]: info: [squeezelite_mc] Starting proxy server... Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 04:03:31 volumio volumio[18708]: info: [1757210611209] CoreMusicLibrary::Adding element Radio Paradise Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 04:03:31 volumio volumio[18708]: Cannot find translation for source SoundCloud Sep 07 04:03:31 volumio volumio[18708]: Cannot find translation for source Radio Paradise Sep 07 04:03:31 volumio sudo[18943]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 04:03:31 volumio sudo[18943]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:31 volumio sudo[18943]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:31 volumio volumio[18708]: info: Volumio Calling Home Sep 07 04:03:31 volumio sudo[18975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 07 04:03:31 volumio sudo[18975]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:31 volumio volumio[18708]: info: [squeezelite_mc] Proxy server started on port 41881 Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 04:03:31 volumio volumio[18708]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 07 04:03:31 volumio volumio[18708]: info: MPD Permissions set Sep 07 04:03:31 volumio volumio[18708]: info: MPD Permissions set Sep 07 04:03:31 volumio volumio[18708]: info: Spotify config file written Sep 07 04:03:31 volumio volumio[18708]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 07 04:03:31 volumio sudo[18994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 07 04:03:31 volumio volumio[18708]: info: VolumeController:: Volume=undefined Mute =false Sep 07 04:03:31 volumio volumio[18708]: info: CoreStateMachine::pushState Sep 07 04:03:31 volumio sudo[18994]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 04:03:31 volumio volumio[18708]: info: CorePlayQueue::getTrack 0 Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::volumioPushState Sep 07 04:03:31 volumio volumio[18708]: info: [squeezelite_mc] Server discovery started Sep 07 04:03:31 volumio volumio[18708]: info: [squeezelite_mc] Player finder started Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 04:03:31 volumio volumio[18708]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 04:03:31 volumio volumio[18708]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 07 04:03:31 volumio systemd[1]: Stopped go-librespot Daemon. Sep 07 04:03:31 volumio volumio[18708]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:03:31 volumio volumio[18708]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 07 04:03:31 volumio volumio[18708]: at doSend (dgram.js:692:16) Sep 07 04:03:31 volumio volumio[18708]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 07 04:03:31 volumio volumio[18708]: at afterDns (dgram.js:638:5) Sep 07 04:03:31 volumio volumio[18708]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 07 04:03:31 volumio volumio[18708]: errno: -101, Sep 07 04:03:31 volumio volumio[18708]: code: 'ENETUNREACH', Sep 07 04:03:31 volumio volumio[18708]: syscall: 'send', Sep 07 04:03:31 volumio volumio[18708]: address: '255.255.255.255', Sep 07 04:03:31 volumio volumio[18708]: port: 3483 Sep 07 04:03:31 volumio volumio[18708]: } Sep 07 04:03:31 volumio volumio[18708]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 04:03:31 volumio go-librespot[18997]: Librespot-go daemon starting... Sep 07 04:03:31 volumio systemd[1]: Started go-librespot Daemon. Sep 07 04:03:31 volumio sudo[18994]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:31 volumio go-librespot[18997]: time="2025-09-07T04:03:31+02:00" level=info msg="generated new device id: 0451b54b58d2820957fbc13b21b89ac7acf2e7ea" Sep 07 04:03:31 volumio go-librespot[18997]: time="2025-09-07T04:03:31+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 07 04:03:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 07 04:03:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 07 04:03:31 volumio dhcpcd[877]: wlan0: leased 192.168.0.120 for infinity Sep 07 04:03:31 volumio avahi-daemon[549]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.120. Sep 07 04:03:31 volumio dhcpcd[877]: wlan0: adding route to 192.168.0.0/24 Sep 07 04:03:31 volumio dhcpcd[877]: wlan0: adding default route via 192.168.0.1 Sep 07 04:03:32 volumio avahi-daemon[549]: New relevant interface wlan0.IPv4 for mDNS. Sep 07 04:03:32 volumio avahi-daemon[549]: Registering new address record for 192.168.0.120 on wlan0.IPv4. Sep 07 04:03:32 volumio sudo[18975]: pam_unix(sudo:session): session closed for user root Sep 07 04:03:32 volumio sudo[19052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 04:02 Sep 07 04:03:32 volumio sudo[19052]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"